builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-047 starttime: 1478435293.33 results: success (0) buildid: 20161106014827 builduid: 1dd9004f42cc496bb47076fba597d6dd revision: 7b52f1fb36165d23f68376a20ccf51db8877096d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-06 04:28:13.329010) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-06 04:28:13.329478) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-06 04:28:13.329782) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-06 04:28:13.353277) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 04:28:13.353558) ========= 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=03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-06 04:28:13-- 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% 85.3M=0s 2016-11-06 04:28:13 (85.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.103908 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 04:28:13.471806) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 04:28:13.472111) ========= 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=03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016967 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 04:28:13.521773) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-06 04:28:13.522101) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7b52f1fb36165d23f68376a20ccf51db8877096d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7b52f1fb36165d23f68376a20ccf51db8877096d --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=03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-06 04:28:13,973 truncating revision to first 12 chars 2016-11-06 04:28:13,973 Setting DEBUG logging. 2016-11-06 04:28:13,973 attempt 1/10 2016-11-06 04:28:13,973 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7b52f1fb3616?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-06 04:28:14,785 unpacking tar archive at: mozilla-aurora-7b52f1fb3616/testing/mozharness/ program finished with exit code 0 elapsedTime=1.154672 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-06 04:28:14.690199) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-06 04:28:14.690481) ========= script_repo_revision: 7b52f1fb36165d23f68376a20ccf51db8877096d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-06 04:28:14.690833) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-06 04:28:14.691104) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-06 04:28:14.699473) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-11-06 04:28:14.699748) ========= /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=03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:28:15 INFO - MultiFileLogger online at 20161106 04:28:15 in /builds/slave/test 04:28:15 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 04:28:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:28:15 INFO - {'append_to_log': False, 04:28:15 INFO - 'base_work_dir': '/builds/slave/test', 04:28:15 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:28:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:28:15 INFO - 'branch': 'Mozilla-Aurora', 04:28:15 INFO - 'buildbot_json_path': 'buildprops.json', 04:28:15 INFO - 'config_files': ('talos/linux_config.py',), 04:28:15 INFO - 'default_actions': ('clobber', 04:28:15 INFO - 'read-buildbot-config', 04:28:15 INFO - 'download-and-extract', 04:28:15 INFO - 'populate-webroot', 04:28:15 INFO - 'create-virtualenv', 04:28:15 INFO - 'install', 04:28:15 INFO - 'run-tests'), 04:28:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:28:15 INFO - 'download_minidump_stackwalk': True, 04:28:15 INFO - 'download_symbols': 'ondemand', 04:28:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:28:15 INFO - 'tooltool.py': '/tools/tooltool.py', 04:28:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:28:15 INFO - '/tools/misc-python/virtualenv.py')}, 04:28:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:28:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:28:15 INFO - 'installer_path': 'installer.exe', 04:28:15 INFO - 'log_level': 'info', 04:28:15 INFO - 'log_name': 'talos', 04:28:15 INFO - 'log_to_console': True, 04:28:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:28:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:28:15 INFO - 'opt_config_files': (), 04:28:15 INFO - 'pip_index': False, 04:28:15 INFO - 'sps_profile': False, 04:28:15 INFO - 'sps_profile_interval': 0, 04:28:15 INFO - 'suite': 'dromaeojs', 04:28:15 INFO - 'system_bits': '32', 04:28:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:28:15 INFO - 'title': 'talos-linux64-ix-047', 04:28:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:28:15 INFO - 'use_talos_json': True, 04:28:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:28:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:28:15 INFO - 'work_dir': 'build'} 04:28:15 INFO - [mozharness: 2016-11-06 12:28:15.246595Z] Running clobber step. 04:28:15 INFO - Running pre-action listener: _resource_record_pre_action 04:28:15 INFO - Running main action method: clobber 04:28:15 INFO - rmtree: /builds/slave/test/build 04:28:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:28:15 INFO - Running post-action listener: _resource_record_post_action 04:28:15 INFO - [mozharness: 2016-11-06 12:28:15.703345Z] Finished clobber step (success) 04:28:15 INFO - [mozharness: 2016-11-06 12:28:15.703447Z] Running read-buildbot-config step. 04:28:15 INFO - Running pre-action listener: _resource_record_pre_action 04:28:15 INFO - Running main action method: read_buildbot_config 04:28:15 INFO - Using buildbot properties: 04:28:15 INFO - { 04:28:15 INFO - "project": "", 04:28:15 INFO - "product": "firefox", 04:28:15 INFO - "script_repo_revision": "production", 04:28:15 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 04:28:15 INFO - "repository": "", 04:28:15 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 04:28:15 INFO - "buildid": "20161106014827", 04:28:15 INFO - "pgo_build": "True", 04:28:15 INFO - "basedir": "/builds/slave/test", 04:28:15 INFO - "buildnumber": 861, 04:28:15 INFO - "slavename": "talos-linux64-ix-047", 04:28:15 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:28:15 INFO - "platform": "ubuntu64_hw", 04:28:15 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 04:28:15 INFO - "branch": "mozilla-aurora", 04:28:15 INFO - "repo_path": "releases/mozilla-aurora", 04:28:15 INFO - "revision": "7b52f1fb36165d23f68376a20ccf51db8877096d", 04:28:15 INFO - "stage_platform": "linux64-pgo", 04:28:15 INFO - "builduid": "1dd9004f42cc496bb47076fba597d6dd", 04:28:15 INFO - "slavebuilddir": "test" 04:28:15 INFO - } 04:28:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 04:28:15 INFO - Running post-action listener: _resource_record_post_action 04:28:15 INFO - [mozharness: 2016-11-06 12:28:15.706198Z] Finished read-buildbot-config step (success) 04:28:15 INFO - [mozharness: 2016-11-06 12:28:15.706288Z] Running download-and-extract step. 04:28:15 INFO - Running pre-action listener: _resource_record_pre_action 04:28:15 INFO - Running main action method: download_and_extract 04:28:15 INFO - mkdir: /builds/slave/test/build/tests 04:28:15 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')]} 04:28:15 INFO - trying https://queue.taskcluster.net/v1/task/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 04:28:15 INFO - Downloading https://queue.taskcluster.net/v1/task/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 04:28:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:28:16 INFO - Downloaded 1361 bytes. 04:28:16 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 04:28:16 INFO - Using the following test package requirements: 04:28:16 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 04:28:16 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:28:16 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 04:28:16 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:28:16 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 04:28:16 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:28:16 INFO - u'jsshell-linux-x86_64.zip'], 04:28:16 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:28:16 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 04:28:16 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 04:28:16 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:28:16 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 04:28:16 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:28:16 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 04:28:16 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:28:16 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 04:28:16 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:28:16 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 04:28:16 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:28:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 04:28:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:28:16 INFO - Fetch https://queue.taskcluster.net/v1/task/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 04:28:16 INFO - Expected file size: 21895828 04:28:21 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:28:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 04:28:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:28:21 INFO - Fetch https://queue.taskcluster.net/v1/task/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 04:28:21 INFO - Expected file size: 21895828 04:28:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 04:28:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:28:26 INFO - Fetch https://queue.taskcluster.net/v1/task/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 04:28:27 INFO - Expected file size: 14414089 04:28:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:28 INFO - trying https://queue.taskcluster.net/v1/task/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 04:28:28 INFO - Downloading https://queue.taskcluster.net/v1/task/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 04:28:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:28:30 INFO - Downloaded 64000213 bytes. 04:28:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 04:28:30 INFO - mkdir: /builds/slave/test/properties 04:28:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:28:30 INFO - Writing to file /builds/slave/test/properties/build_url 04:28:30 INFO - Contents: 04:28:30 INFO - build_url:https://queue.taskcluster.net/v1/task/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 04:28:31 INFO - Running post-action listener: _resource_record_post_action 04:28:31 INFO - Running post-action listener: set_extra_try_arguments 04:28:31 INFO - [mozharness: 2016-11-06 12:28:31.325379Z] Finished download-and-extract step (success) 04:28:31 INFO - [mozharness: 2016-11-06 12:28:31.325607Z] Running populate-webroot step. 04:28:31 INFO - Running pre-action listener: _resource_record_pre_action 04:28:31 INFO - Running main action method: populate_webroot 04:28:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:28:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:28:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:28:31 INFO - '--tppagecycles', 04:28:31 INFO - '7'], 04:28:31 INFO - 'tests': ['tsvgm']}}, 04:28:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:28:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:28:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:28:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:28:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:28:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:28:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:28:31 INFO - 'talos_options': ['--disable-e10s'], 04:28:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:28:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:28:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:28:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:28:31 INFO - 'talos_options': ['--disable-e10s'], 04:28:31 INFO - 'tests': ['damp', 'tps']}, 04:28:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:28:31 INFO - 'tests': ['damp', 'tps']}, 04:28:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:28:31 INFO - 'tests': ['dromaeo_dom']}, 04:28:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:28:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:28:31 INFO - 'tests': ['basic_compositor_video']}, 04:28:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:28:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:28:31 INFO - 'tests': ['a11yr', 04:28:31 INFO - 'ts_paint', 04:28:31 INFO - 'tpaint', 04:28:31 INFO - 'sessionrestore', 04:28:31 INFO - 'sessionrestore_no_auto_restore', 04:28:31 INFO - 'tabpaint']}, 04:28:31 INFO - 'other-e10s': {'tests': ['a11yr', 04:28:31 INFO - 'ts_paint', 04:28:31 INFO - 'tpaint', 04:28:31 INFO - 'sessionrestore', 04:28:31 INFO - 'sessionrestore_no_auto_restore', 04:28:31 INFO - 'tabpaint']}, 04:28:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:28:31 INFO - 'ts_paint', 04:28:31 INFO - 'tpaint', 04:28:31 INFO - 'sessionrestore', 04:28:31 INFO - 'sessionrestore_no_auto_restore', 04:28:31 INFO - 'tabpaint']}, 04:28:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:28:31 INFO - 'ts_paint', 04:28:31 INFO - 'tpaint', 04:28:31 INFO - 'sessionrestore', 04:28:31 INFO - 'sessionrestore_no_auto_restore', 04:28:31 INFO - 'tabpaint']}, 04:28:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:28:31 INFO - 'tests': ['a11yr', 04:28:31 INFO - 'ts_paint', 04:28:31 INFO - 'tpaint', 04:28:31 INFO - 'sessionrestore', 04:28:31 INFO - 'sessionrestore_no_auto_restore', 04:28:31 INFO - 'tabpaint']}, 04:28:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:28:31 INFO - 'tests': ['a11yr', 04:28:31 INFO - 'ts_paint', 04:28:31 INFO - 'tpaint', 04:28:31 INFO - 'sessionrestore', 04:28:31 INFO - 'sessionrestore_no_auto_restore', 04:28:31 INFO - 'tabpaint']}, 04:28:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:28:31 INFO - 'tests': ['tsvgx', 04:28:31 INFO - 'tsvgr_opacity', 04:28:31 INFO - 'tart', 04:28:31 INFO - 'tscrollx', 04:28:31 INFO - 'cart']}, 04:28:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:28:31 INFO - 'tsvgr_opacity', 04:28:31 INFO - 'tart', 04:28:31 INFO - 'tscrollx', 04:28:31 INFO - 'cart']}, 04:28:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:28:31 INFO - 'talos_options': ['--disable-e10s'], 04:28:31 INFO - 'tests': ['tp5o']}, 04:28:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:28:31 INFO - 'tests': ['tp5o']}, 04:28:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:28:31 INFO - 'talos_options': ['--disable-e10s', 04:28:31 INFO - '--xperf_path', 04:28:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:28:31 INFO - 'tests': ['tp5n']}, 04:28:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:28:31 INFO - 'talos_options': ['--xperf_path', 04:28:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:28:31 INFO - 'tests': ['tp5n']}}, 04:28:31 INFO - 'talos.zip': {'path': '', 04:28:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:28:31 INFO - Running post-action listener: _resource_record_post_action 04:28:31 INFO - [mozharness: 2016-11-06 12:28:31.345692Z] Finished populate-webroot step (success) 04:28:31 INFO - [mozharness: 2016-11-06 12:28:31.345782Z] Running create-virtualenv step. 04:28:31 INFO - Running pre-action listener: _resource_record_pre_action 04:28:31 INFO - Running main action method: create_virtualenv 04:28:31 INFO - Creating virtualenv /builds/slave/test/build/venv 04:28:31 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 04:28:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:28:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:28:31 INFO - Using real prefix '/usr' 04:28:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:28:31 INFO - Installing distribute.............................................................................................................................................................................................done. 04:28:33 INFO - Installing pip.................done. 04:28:33 INFO - Return code: 0 04:28:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:28:33 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')]} 04:28:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:33 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')]} 04:28:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:33 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 0x7f6946e62f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x259c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2669ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2666f60>, '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 0x2667d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26681d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732', '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 04:28:33 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 04:28:33 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 04:28:33 INFO - Using env: {'DISPLAY': ':0', 04:28:33 INFO - 'HOME': '/home/cltbld', 04:28:33 INFO - 'LANG': 'en_US.UTF-8', 04:28:33 INFO - 'LANGUAGE': 'en_US:en', 04:28:33 INFO - 'LOGNAME': 'cltbld', 04:28:33 INFO - 'MAIL': '/var/mail/cltbld', 04:28:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:33 INFO - 'MOZ_NO_REMOTE': '1', 04:28:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:28:33 INFO - 'NO_EM_RESTART': '1', 04:28:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:28:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:33 INFO - 'PWD': '/builds/slave/test', 04:28:33 INFO - 'SHELL': '/bin/bash', 04:28:33 INFO - 'SHLVL': '1', 04:28:33 INFO - 'TERM': 'linux', 04:28:33 INFO - 'TMOUT': '86400', 04:28:33 INFO - 'USER': 'cltbld', 04:28:33 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732', 04:28:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:33 INFO - '_': '/tools/buildbot/bin/python'} 04:28:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:34 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 04:28:34 INFO - Cleaning up... 04:28:34 INFO - Return code: 0 04:28:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:28:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:34 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 0x7f6946e62f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x259c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2669ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2666f60>, '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 0x2667d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26681d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732', '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 04:28:34 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 04:28:34 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 04:28:34 INFO - Using env: {'DISPLAY': ':0', 04:28:34 INFO - 'HOME': '/home/cltbld', 04:28:34 INFO - 'LANG': 'en_US.UTF-8', 04:28:34 INFO - 'LANGUAGE': 'en_US:en', 04:28:34 INFO - 'LOGNAME': 'cltbld', 04:28:34 INFO - 'MAIL': '/var/mail/cltbld', 04:28:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:34 INFO - 'MOZ_NO_REMOTE': '1', 04:28:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:28:34 INFO - 'NO_EM_RESTART': '1', 04:28:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:28:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:34 INFO - 'PWD': '/builds/slave/test', 04:28:34 INFO - 'SHELL': '/bin/bash', 04:28:34 INFO - 'SHLVL': '1', 04:28:34 INFO - 'TERM': 'linux', 04:28:34 INFO - 'TMOUT': '86400', 04:28:34 INFO - 'USER': 'cltbld', 04:28:34 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732', 04:28:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:34 INFO - '_': '/tools/buildbot/bin/python'} 04:28:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:34 INFO - Downloading/unpacking psutil>=3.1.1 04:28:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:28:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:28:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:28:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:28:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:28:36 INFO - Installing collected packages: psutil 04:28:36 INFO - Running setup.py install for psutil 04:28:36 INFO - building 'psutil._psutil_linux' extension 04:28:36 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 04:28:36 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 04:28:36 INFO - building 'psutil._psutil_posix' extension 04:28:36 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 04:28:36 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 04:28:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:28:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:28:36 INFO - Successfully installed psutil 04:28:36 INFO - Cleaning up... 04:28:36 INFO - Return code: 0 04:28:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:28:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6946e62f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x259c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2669ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2666f60>, '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 0x2667d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26681d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732', '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 04:28:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:28:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:28:36 INFO - Using env: {'DISPLAY': ':0', 04:28:36 INFO - 'HOME': '/home/cltbld', 04:28:36 INFO - 'LANG': 'en_US.UTF-8', 04:28:36 INFO - 'LANGUAGE': 'en_US:en', 04:28:36 INFO - 'LOGNAME': 'cltbld', 04:28:36 INFO - 'MAIL': '/var/mail/cltbld', 04:28:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:36 INFO - 'MOZ_NO_REMOTE': '1', 04:28:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:28:36 INFO - 'NO_EM_RESTART': '1', 04:28:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:28:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:36 INFO - 'PWD': '/builds/slave/test', 04:28:36 INFO - 'SHELL': '/bin/bash', 04:28:36 INFO - 'SHLVL': '1', 04:28:36 INFO - 'TERM': 'linux', 04:28:36 INFO - 'TMOUT': '86400', 04:28:36 INFO - 'USER': 'cltbld', 04:28:36 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732', 04:28:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:36 INFO - '_': '/tools/buildbot/bin/python'} 04:28:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:28:37 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 04:28:37 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 04:28:37 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 04:28:37 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 04:28:37 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 04:28:37 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 04:28:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:28:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:28:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:28:39 INFO - Installing collected packages: mozsystemmonitor 04:28:39 INFO - Running setup.py install for mozsystemmonitor 04:28:39 INFO - Successfully installed mozsystemmonitor 04:28:39 INFO - Cleaning up... 04:28:39 INFO - Return code: 0 04:28:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:28:39 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')]} 04:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:39 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')]} 04:28:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:39 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 0x7f6946e62f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x259c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2669ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2666f60>, '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 0x2667d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26681d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732', '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 04:28:39 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 04:28:39 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 04:28:39 INFO - Using env: {'DISPLAY': ':0', 04:28:39 INFO - 'HOME': '/home/cltbld', 04:28:39 INFO - 'LANG': 'en_US.UTF-8', 04:28:39 INFO - 'LANGUAGE': 'en_US:en', 04:28:39 INFO - 'LOGNAME': 'cltbld', 04:28:39 INFO - 'MAIL': '/var/mail/cltbld', 04:28:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:39 INFO - 'MOZ_NO_REMOTE': '1', 04:28:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:28:39 INFO - 'NO_EM_RESTART': '1', 04:28:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:28:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:39 INFO - 'PWD': '/builds/slave/test', 04:28:39 INFO - 'SHELL': '/bin/bash', 04:28:39 INFO - 'SHLVL': '1', 04:28:39 INFO - 'TERM': 'linux', 04:28:39 INFO - 'TMOUT': '86400', 04:28:39 INFO - 'USER': 'cltbld', 04:28:39 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732', 04:28:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:39 INFO - '_': '/tools/buildbot/bin/python'} 04:28:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:39 INFO - Downloading/unpacking jsonschema==2.5.1 04:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:28:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:28:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:28:41 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 04:28:41 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 04:28:41 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 04:28:41 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 04:28:41 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 04:28:41 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 04:28:41 INFO - Downloading functools32-3.2.3-2.tar.gz 04:28:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:28:41 INFO - warning: no files found matching '*.txt' 04:28:41 INFO - no previously-included directories found matching 'build' 04:28:41 INFO - no previously-included directories found matching 'dist' 04:28:41 INFO - no previously-included directories found matching '.git*' 04:28:41 INFO - Installing collected packages: jsonschema, functools32 04:28:41 INFO - Running setup.py install for functools32 04:28:41 INFO - warning: no files found matching '*.txt' 04:28:41 INFO - no previously-included directories found matching 'build' 04:28:41 INFO - no previously-included directories found matching 'dist' 04:28:41 INFO - no previously-included directories found matching '.git*' 04:28:41 INFO - Successfully installed jsonschema functools32 04:28:41 INFO - Cleaning up... 04:28:41 INFO - Return code: 0 04:28:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:28:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f6946e62f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x259c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2669ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2666f60>, '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 0x2667d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26681d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732', '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 04:28:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:28:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:28:41 INFO - Using env: {'DISPLAY': ':0', 04:28:41 INFO - 'HOME': '/home/cltbld', 04:28:41 INFO - 'LANG': 'en_US.UTF-8', 04:28:41 INFO - 'LANGUAGE': 'en_US:en', 04:28:41 INFO - 'LOGNAME': 'cltbld', 04:28:41 INFO - 'MAIL': '/var/mail/cltbld', 04:28:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:41 INFO - 'MOZ_NO_REMOTE': '1', 04:28:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:28:41 INFO - 'NO_EM_RESTART': '1', 04:28:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:28:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:41 INFO - 'PWD': '/builds/slave/test', 04:28:41 INFO - 'SHELL': '/bin/bash', 04:28:41 INFO - 'SHLVL': '1', 04:28:41 INFO - 'TERM': 'linux', 04:28:41 INFO - 'TMOUT': '86400', 04:28:41 INFO - 'USER': 'cltbld', 04:28:41 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732', 04:28:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:41 INFO - '_': '/tools/buildbot/bin/python'} 04:28:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:28:42 INFO - Cleaning up... 04:28:42 INFO - Return code: 0 04:28:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:28:42 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')]} 04:28:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:42 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')]} 04:28:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:42 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 0x7f6946e62f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x259c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2669ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2666f60>, '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 0x2667d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26681d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732', '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 04:28:42 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 04:28:42 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 04:28:42 INFO - Using env: {'DISPLAY': ':0', 04:28:42 INFO - 'HOME': '/home/cltbld', 04:28:42 INFO - 'LANG': 'en_US.UTF-8', 04:28:42 INFO - 'LANGUAGE': 'en_US:en', 04:28:42 INFO - 'LOGNAME': 'cltbld', 04:28:42 INFO - 'MAIL': '/var/mail/cltbld', 04:28:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:42 INFO - 'MOZ_NO_REMOTE': '1', 04:28:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:28:42 INFO - 'NO_EM_RESTART': '1', 04:28:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:28:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:42 INFO - 'PWD': '/builds/slave/test', 04:28:42 INFO - 'SHELL': '/bin/bash', 04:28:42 INFO - 'SHLVL': '1', 04:28:42 INFO - 'TERM': 'linux', 04:28:42 INFO - 'TMOUT': '86400', 04:28:42 INFO - 'USER': 'cltbld', 04:28:42 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732', 04:28:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:42 INFO - '_': '/tools/buildbot/bin/python'} 04:28:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:42 INFO - Downloading/unpacking blobuploader==1.2.4 04:28:42 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 04:28:42 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 04:28:42 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 04:28:42 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 04:28:42 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 04:28:42 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 04:28:44 INFO - Downloading blobuploader-1.2.4.tar.gz 04:28:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:28:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:28:44 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 04:28:44 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 04:28:44 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 04:28:44 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 04:28:44 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 04:28:44 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 04:28:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:28:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:28:44 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 04:28:44 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 04:28:44 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 04:28:44 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 04:28:44 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 04:28:44 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 04:28:44 INFO - Downloading docopt-0.6.1.tar.gz 04:28:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:28:45 INFO - Installing collected packages: blobuploader, requests, docopt 04:28:45 INFO - Running setup.py install for blobuploader 04:28:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:28:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:28:45 INFO - Running setup.py install for requests 04:28:45 INFO - Running setup.py install for docopt 04:28:45 INFO - Successfully installed blobuploader requests docopt 04:28:45 INFO - Cleaning up... 04:28:45 INFO - Return code: 0 04:28:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:28:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:45 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 0x7f6946e62f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x259c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2669ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2666f60>, '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 0x2667d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26681d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732', '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 04:28:45 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 04:28:45 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 04:28:45 INFO - Using env: {'DISPLAY': ':0', 04:28:45 INFO - 'HOME': '/home/cltbld', 04:28:45 INFO - 'LANG': 'en_US.UTF-8', 04:28:45 INFO - 'LANGUAGE': 'en_US:en', 04:28:45 INFO - 'LOGNAME': 'cltbld', 04:28:45 INFO - 'MAIL': '/var/mail/cltbld', 04:28:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:45 INFO - 'MOZ_NO_REMOTE': '1', 04:28:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:28:45 INFO - 'NO_EM_RESTART': '1', 04:28:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:28:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:45 INFO - 'PWD': '/builds/slave/test', 04:28:45 INFO - 'SHELL': '/bin/bash', 04:28:45 INFO - 'SHLVL': '1', 04:28:45 INFO - 'TERM': 'linux', 04:28:45 INFO - 'TMOUT': '86400', 04:28:45 INFO - 'USER': 'cltbld', 04:28:45 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732', 04:28:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:45 INFO - '_': '/tools/buildbot/bin/python'} 04:28:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:28:45 INFO - Running setup.py (path:/tmp/pip-npRwfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:28:45 INFO - Running setup.py (path:/tmp/pip-kJXyO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:28:45 INFO - Running setup.py (path:/tmp/pip-xJuwpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:28:46 INFO - Running setup.py (path:/tmp/pip-DqSLUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:28:46 INFO - Running setup.py (path:/tmp/pip-qCZje2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:28:46 INFO - Running setup.py (path:/tmp/pip-mIjJuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:28:46 INFO - Running setup.py (path:/tmp/pip-22Z1ob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:28:46 INFO - Running setup.py (path:/tmp/pip-2IU8fz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:28:46 INFO - Running setup.py (path:/tmp/pip-1H0Vcl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:28:46 INFO - Running setup.py (path:/tmp/pip-jp2Xns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:28:46 INFO - Running setup.py (path:/tmp/pip-gHU_g8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:28:46 INFO - Running setup.py (path:/tmp/pip-dYQdyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:28:46 INFO - Running setup.py (path:/tmp/pip-254t73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:28:46 INFO - Running setup.py (path:/tmp/pip-alBPzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:28:46 INFO - Running setup.py (path:/tmp/pip-wrB16Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:28:46 INFO - Running setup.py (path:/tmp/pip-mKdzsy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:28:46 INFO - Running setup.py (path:/tmp/pip-9BsG4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:28:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:28:47 INFO - Running setup.py install for manifestparser 04:28:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:28:47 INFO - Running setup.py install for mozcrash 04:28:47 INFO - Running setup.py install for mozdebug 04:28:47 INFO - Running setup.py install for mozdevice 04:28:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:28:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:28:47 INFO - Running setup.py install for mozfile 04:28:47 INFO - Running setup.py install for mozhttpd 04:28:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:28:47 INFO - Running setup.py install for mozinfo 04:28:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:28:47 INFO - Running setup.py install for mozInstall 04:28:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:28:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:28:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:28:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:28:47 INFO - Running setup.py install for mozleak 04:28:47 INFO - Running setup.py install for mozlog 04:28:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:28:48 INFO - Running setup.py install for moznetwork 04:28:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:28:48 INFO - Running setup.py install for mozprocess 04:28:48 INFO - Running setup.py install for mozprofile 04:28:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:28:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:28:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:28:48 INFO - Running setup.py install for mozrunner 04:28:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:28:48 INFO - Running setup.py install for mozscreenshot 04:28:48 INFO - Running setup.py install for moztest 04:28:48 INFO - Running setup.py install for mozversion 04:28:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:28:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:28:48 INFO - Cleaning up... 04:28:48 INFO - Return code: 0 04:28:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:28:48 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')]} 04:28:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:48 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')]} 04:28:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:48 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 0x7f6946e62f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x259c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2669ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2666f60>, '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 0x2667d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26681d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732', '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 04:28:48 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 04:28:48 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 04:28:48 INFO - Using env: {'DISPLAY': ':0', 04:28:48 INFO - 'HOME': '/home/cltbld', 04:28:48 INFO - 'LANG': 'en_US.UTF-8', 04:28:48 INFO - 'LANGUAGE': 'en_US:en', 04:28:48 INFO - 'LOGNAME': 'cltbld', 04:28:48 INFO - 'MAIL': '/var/mail/cltbld', 04:28:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:48 INFO - 'MOZ_NO_REMOTE': '1', 04:28:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:28:48 INFO - 'NO_EM_RESTART': '1', 04:28:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:28:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:48 INFO - 'PWD': '/builds/slave/test', 04:28:48 INFO - 'SHELL': '/bin/bash', 04:28:48 INFO - 'SHLVL': '1', 04:28:48 INFO - 'TERM': 'linux', 04:28:48 INFO - 'TMOUT': '86400', 04:28:48 INFO - 'USER': 'cltbld', 04:28:48 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732', 04:28:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:48 INFO - '_': '/tools/buildbot/bin/python'} 04:28:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:28:49 INFO - Running setup.py (path:/tmp/pip-ByPRWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:28:49 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)) 04:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:28:49 INFO - Running setup.py (path:/tmp/pip-_jJXkv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:28:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:28:49 INFO - Running setup.py (path:/tmp/pip-JXUBtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:28:49 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)) 04:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:28:49 INFO - Running setup.py (path:/tmp/pip-Ir1M3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:28:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:28:49 INFO - Running setup.py (path:/tmp/pip-1bfTyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:28:49 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)) 04:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:28:49 INFO - Running setup.py (path:/tmp/pip-9qMXvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:28:49 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)) 04:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:28:49 INFO - Running setup.py (path:/tmp/pip-N0Fj9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:28:49 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)) 04:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:28:49 INFO - Running setup.py (path:/tmp/pip-wmPEZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:28:49 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)) 04:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:28:49 INFO - Running setup.py (path:/tmp/pip-CBvmzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:28:49 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)) 04:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:28:49 INFO - Running setup.py (path:/tmp/pip-GPpTpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:28:49 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)) 04:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:28:49 INFO - Running setup.py (path:/tmp/pip-ZFc1vn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:28:49 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)) 04:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:28:49 INFO - Running setup.py (path:/tmp/pip-nOnWcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:28:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:28:49 INFO - Running setup.py (path:/tmp/pip-llKgO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:28:49 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)) 04:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:28:49 INFO - Running setup.py (path:/tmp/pip-DfFxoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:28:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:28:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:28:50 INFO - Running setup.py (path:/tmp/pip-jnVCnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:28:50 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)) 04:28:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:28:50 INFO - Running setup.py (path:/tmp/pip-wn00Zl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:28:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:28:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:28:50 INFO - Running setup.py (path:/tmp/pip-3Tc85N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:28:50 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)) 04:28:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:28:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:28:50 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)) 04:28:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:28:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:28:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:28:50 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 04:28:50 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 04:28:50 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 04:28:50 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 04:28:50 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 04:28:50 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 04:28:52 INFO - Downloading blessings-1.6.tar.gz 04:28:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:28:52 INFO - Installing collected packages: blessings 04:28:52 INFO - Running setup.py install for blessings 04:28:52 INFO - Successfully installed blessings 04:28:52 INFO - Cleaning up... 04:28:52 INFO - Return code: 0 04:28:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:28:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:28:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:28:52 INFO - Reading from file tmpfile_stdout 04:28:52 INFO - Current package versions: 04:28:52 INFO - argparse == 1.2.1 04:28:52 INFO - blessings == 1.6 04:28:52 INFO - blobuploader == 1.2.4 04:28:52 INFO - docopt == 0.6.1 04:28:52 INFO - functools32 == 3.2.3-2 04:28:52 INFO - jsonschema == 2.5.1 04:28:52 INFO - manifestparser == 1.1 04:28:52 INFO - mozInstall == 1.12 04:28:52 INFO - mozcrash == 0.17 04:28:52 INFO - mozdebug == 0.1 04:28:52 INFO - mozdevice == 0.48 04:28:52 INFO - mozfile == 1.2 04:28:52 INFO - mozhttpd == 0.7 04:28:52 INFO - mozinfo == 0.9 04:28:52 INFO - mozleak == 0.1 04:28:52 INFO - mozlog == 3.4 04:28:52 INFO - moznetwork == 0.27 04:28:52 INFO - mozprocess == 0.23 04:28:52 INFO - mozprofile == 0.28 04:28:52 INFO - mozrunner == 6.12 04:28:52 INFO - mozscreenshot == 0.1 04:28:52 INFO - mozsystemmonitor == 0.3 04:28:52 INFO - moztest == 0.7 04:28:52 INFO - mozversion == 1.4 04:28:52 INFO - psutil == 3.1.1 04:28:52 INFO - requests == 1.2.3 04:28:52 INFO - wsgiref == 0.1.2 04:28:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:28:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:52 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 0x7f6946e62f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x259c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2669ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2666f60>, '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 0x2667d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26681d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732', '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 04:28:52 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 04:28:52 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 04:28:52 INFO - Using env: {'DISPLAY': ':0', 04:28:52 INFO - 'HOME': '/home/cltbld', 04:28:52 INFO - 'LANG': 'en_US.UTF-8', 04:28:52 INFO - 'LANGUAGE': 'en_US:en', 04:28:52 INFO - 'LOGNAME': 'cltbld', 04:28:52 INFO - 'MAIL': '/var/mail/cltbld', 04:28:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:52 INFO - 'MOZ_NO_REMOTE': '1', 04:28:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:28:52 INFO - 'NO_EM_RESTART': '1', 04:28:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:28:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:52 INFO - 'PWD': '/builds/slave/test', 04:28:52 INFO - 'SHELL': '/bin/bash', 04:28:52 INFO - 'SHLVL': '1', 04:28:52 INFO - 'TERM': 'linux', 04:28:52 INFO - 'TMOUT': '86400', 04:28:52 INFO - 'USER': 'cltbld', 04:28:52 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732', 04:28:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:52 INFO - '_': '/tools/buildbot/bin/python'} 04:28:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:52 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)) 04:28:52 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)) 04:28:52 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)) 04:28:52 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)) 04:28:52 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)) 04:28:52 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)) 04:28:52 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)) 04:28:52 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)) 04:28:52 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)) 04:28:52 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)) 04:28:52 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)) 04:28:52 INFO - Cleaning up... 04:28:52 INFO - Return code: 0 04:28:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:28:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6946e62f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x259c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2669ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2666f60>, '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 0x2667d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26681d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732', '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 04:28:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:28:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:28:52 INFO - Using env: {'DISPLAY': ':0', 04:28:52 INFO - 'HOME': '/home/cltbld', 04:28:52 INFO - 'LANG': 'en_US.UTF-8', 04:28:52 INFO - 'LANGUAGE': 'en_US:en', 04:28:52 INFO - 'LOGNAME': 'cltbld', 04:28:52 INFO - 'MAIL': '/var/mail/cltbld', 04:28:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:52 INFO - 'MOZ_NO_REMOTE': '1', 04:28:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:28:52 INFO - 'NO_EM_RESTART': '1', 04:28:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:28:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:52 INFO - 'PWD': '/builds/slave/test', 04:28:52 INFO - 'SHELL': '/bin/bash', 04:28:52 INFO - 'SHLVL': '1', 04:28:52 INFO - 'TERM': 'linux', 04:28:52 INFO - 'TMOUT': '86400', 04:28:52 INFO - 'USER': 'cltbld', 04:28:52 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732', 04:28:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:52 INFO - '_': '/tools/buildbot/bin/python'} 04:28:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:28:53 INFO - Cleaning up... 04:28:53 INFO - Return code: 0 04:28:53 INFO - Running post-action listener: _resource_record_post_action 04:28:53 INFO - Running post-action listener: _start_resource_monitoring 04:28:53 INFO - Starting resource monitoring. 04:28:53 INFO - [mozharness: 2016-11-06 12:28:53.193535Z] Finished create-virtualenv step (success) 04:28:53 INFO - [mozharness: 2016-11-06 12:28:53.193993Z] Running install step. 04:28:53 INFO - Running pre-action listener: _resource_record_pre_action 04:28:53 INFO - Running main action method: install 04:28:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:28:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:28:53 INFO - Reading from file tmpfile_stdout 04:28:53 INFO - Detecting whether we're running mozinstall >=1.0... 04:28:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:28:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:28:53 INFO - Reading from file tmpfile_stdout 04:28:53 INFO - Output received: 04:28:53 INFO - Usage: mozinstall [options] installer 04:28:53 INFO - Options: 04:28:53 INFO - -h, --help show this help message and exit 04:28:53 INFO - -d DEST, --destination=DEST 04:28:53 INFO - Directory to install application into. [default: 04:28:53 INFO - "/builds/slave/test"] 04:28:53 INFO - --app=APP Application being installed. [default: firefox] 04:28:53 INFO - mkdir: /builds/slave/test/build/application 04:28:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:28:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:29:10 INFO - Reading from file tmpfile_stdout 04:29:10 INFO - Output received: 04:29:10 INFO - /builds/slave/test/build/application/firefox/firefox 04:29:10 INFO - Running post-action listener: _resource_record_post_action 04:29:10 INFO - [mozharness: 2016-11-06 12:29:10.103291Z] Finished install step (success) 04:29:10 INFO - [mozharness: 2016-11-06 12:29:10.103536Z] Running run-tests step. 04:29:10 INFO - Running pre-action listener: _resource_record_pre_action 04:29:10 INFO - Running main action method: run_tests 04:29:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:29:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:29:10 INFO - Python 2.7.3 04:29:10 INFO - Return code: 0 04:29:10 INFO - grabbing minidump binary from tooltool 04:29: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')]} 04:29:10 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 0x2666f60>, '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 0x2667d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26681d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:29:10 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 04:29:10 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 04:29:10 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 04:29:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:29:10 INFO - Return code: 0 04:29:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:29:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:29:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:29:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:29:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:29:10 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/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 04:29:10 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/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-047 --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 04:29:10 INFO - Using env: {'DISPLAY': ':0', 04:29:10 INFO - 'HOME': '/home/cltbld', 04:29:10 INFO - 'LANG': 'en_US.UTF-8', 04:29:10 INFO - 'LANGUAGE': 'en_US:en', 04:29:10 INFO - 'LOGNAME': 'cltbld', 04:29:10 INFO - 'MAIL': '/var/mail/cltbld', 04:29:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:29:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:29:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:10 INFO - 'MOZ_NO_REMOTE': '1', 04:29:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:29:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:29:10 INFO - 'NO_EM_RESTART': '1', 04:29:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:29:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:10 INFO - 'PWD': '/builds/slave/test', 04:29:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:29:10 INFO - 'SHELL': '/bin/bash', 04:29:10 INFO - 'SHLVL': '1', 04:29:10 INFO - 'TERM': 'linux', 04:29:10 INFO - 'TMOUT': '86400', 04:29:10 INFO - 'USER': 'cltbld', 04:29:10 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732', 04:29:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:10 INFO - '_': '/tools/buildbot/bin/python'} 04:29:10 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/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 04:29:10 INFO - mozversion application_buildid: 20161106014827 04:29:10 INFO - mozversion application_changeset: 7b52f1fb36165d23f68376a20ccf51db8877096d 04:29:10 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:29:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:29:10 INFO - mozversion application_name: Firefox 04:29:10 INFO - mozversion application_remotingname: firefox-dev 04:29:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:29:10 INFO - mozversion application_vendor: Mozilla 04:29:10 INFO - mozversion application_version: 51.0a2 04:29:10 INFO - mozversion platform_buildid: 20161106014827 04:29:10 INFO - mozversion platform_changeset: 7b52f1fb36165d23f68376a20ccf51db8877096d 04:29:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:29:10 INFO - mozversion platform_version: 51.0a2 04:29:10 INFO - using testdate: 1478435350 04:29:10 INFO - actual date: 1478435350 04:29:10 INFO - starting webserver on 'localhost:55320' 04:29:10 INFO - SUITE-START | Running 2 tests 04:29:10 INFO - TEST-START | dromaeo_css 04:29:10 INFO - operating with platform_type : linux_ 04:29:10 INFO - Initialising browser for dromaeo_css test... 04:29:10 INFO - TEST-INFO | started process 6667 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV_iRLf/profile http://localhost:55320/getInfo.html) 04:29:14 INFO - PROCESS | 6667 | __metrics Screen width/height:1600/1200 04:29:14 INFO - PROCESS | 6667 | colorDepth:24 04:29:14 INFO - PROCESS | 6667 | Browser inner width/height: 1024/697 04:29:14 INFO - PROCESS | 6667 | __metrics 04:29:14 INFO - PROCESS | 6667 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:29:14 INFO - PROCESS | 6667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:29:15 INFO - PROCESS | 6667 | console.error: PushService: 04:29:15 INFO - PROCESS | 6667 | stateChangeProcessEnqueue: Error transitioning state 04:29:15 INFO - PROCESS | 6667 | UnknownError 04:29:15 INFO - TEST-INFO | 6667: exit 0 04:29:15 INFO - Browser initialized. 04:29:15 INFO - Running cycle 1/1 for dromaeo_css test... 04:29:15 INFO - TEST-INFO | started process 6759 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV_iRLf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:29:27 INFO - PROCESS | 6759 | RSS: Main: 148516864 04:29:27 INFO - PROCESS | 6759 | 04:30:18 INFO - PROCESS | 6759 | 1478435418791 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:32:27 INFO - PROCESS | 6759 | Cycle 1(1): loaded http://localhost:55320/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55320/tests/dromaeo/cssquery-ext.html) 04:32:28 INFO - PROCESS | 6759 | RSS: Main: 240013312 04:32:28 INFO - PROCESS | 6759 | 04:35:31 INFO - PROCESS | 6759 | Cycle 1(1): loaded http://localhost:55320/tests/dromaeo/cssquery-ext.html (next: http://localhost:55320/tests/dromaeo/cssquery-jquery.html) 04:35:31 INFO - PROCESS | 6759 | RSS: Main: 238559232 04:35:31 INFO - PROCESS | 6759 | 04:38:33 INFO - PROCESS | 6759 | Cycle 1(1): loaded http://localhost:55320/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55320/tests/dromaeo/cssquery-mootools.html) 04:38:34 INFO - PROCESS | 6759 | RSS: Main: 202989568 04:38:34 INFO - PROCESS | 6759 | 04:41:36 INFO - PROCESS | 6759 | Cycle 1(1): loaded http://localhost:55320/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55320/tests/dromaeo/cssquery-prototype.html) 04:41:36 INFO - PROCESS | 6759 | RSS: Main: 177102848 04:41:36 INFO - PROCESS | 6759 | 04:44:39 INFO - PROCESS | 6759 | Cycle 1(1): loaded http://localhost:55320/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55320/tests/dromaeo/cssquery-yui.html) 04:44:40 INFO - PROCESS | 6759 | RSS: Main: 177254400 04:44:40 INFO - PROCESS | 6759 | 04:47:42 INFO - PROCESS | 6759 | Cycle 1(1): loaded http://localhost:55320/tests/dromaeo/cssquery-yui.html (next: http://localhost:55320/tests/dromaeo/cssquery-dojo.html) 04:47:42 INFO - PROCESS | 6759 | RSS: Main: 265007104 04:47:42 INFO - PROCESS | 6759 | 04:47:42 INFO - PROCESS | 6759 | __start_tp_report 04:47:42 INFO - PROCESS | 6759 | _x_x_mozilla_page_load 04:47:42 INFO - PROCESS | 6759 | _x_x_mozilla_page_load_details 04:47:42 INFO - PROCESS | 6759 | |i|pagename|runs| 04:47:42 INFO - PROCESS | 6759 | |0;dojo.html;3099.318150006998;3135.3885992231526;3142.6228852537693;3157.1633517117953;3174.9523757143647;5482.177673348995;5509.33887933448;5531.585131115173;5554.833354999347;5583.888322233553;5505.229267902487;5585.553155747541;5593.580481463886;5597.496225339719;5597.580181486394;5028.371453568295;5054.115529782273;5069.923951140718;5110.463401342861;5146.0222557714105;5005.449400565934;5019.372578427697;5084.440711521748;5140.357455318085;5147.639665223436;4978.377702787152;5009.2486127080865;5011.84964451067;5033.798648054073;5043.39479262489;5043.773030213631;5081.974590127063;5134.460881607452;5143.996920600484;5149.794008239666;4832.8791780205775;5006.199008158714;5026.648134630577;5062.594992400599;5086;5344.866378340573;5351.545118664918;5385.838424847261;5419.674819510842;5435;5835.620684655484;5848.912266316009;5904.025835737141;5912.852178695524;5912.911306330366;5794.304683437972;5944.821655350346;5957.940420595782;5958.6424814511265;5962.910556341659;5465.180222966547;5512.503874651261;5563.221149039137;5575.776968921279;5647.209390685307;6086.117512960596;6129.693515324216;6129.96935015331;6132.50939924805;6137.508999280047;5871.55963302754;5872.471477567039;5886.381929897385;5888.263967004124;5890.793822216202;5835.591508594358;5845.766169353178;5871.911921321183;5905.350411454737;5915.142304365843;5931.288245410578;5931.940680593225;5932.110183472513;5937.910931336033;5959.7914073008105;5885.411458854166;5934.228550288435;5950.404959504015;5951.553633477506;5979.073243647242;4474.865754027319;4505.098980203907;4506.864794056183;4518.4126063612175;4556.498785133633;4035.1526179502634;4061.878143655695;4068.5117785865887;4083.2037752637953;4092.6112019358116;5681.829545113653;5683.374828768832;5688.77244910199;5689.08974564076;5690.032694441955;5808.128780682931;5857.062869940789;5883.882322353615;5962.34414214436;5965.701714914237;5635.549156067505;5700.942990570041;5703.315602127771;5710.343310519261;5736.5123964462655;5173.120569503193;5173.611979101583;5175.7153356564995;5182;5182.041322355376;5978.880422391615;6084.482818960438;6111.174991376108;6123.173371594867;6124.938750612526;5860.765569377178;5867.501262392744;5941.1088336749835;5944.256967879015;6017.849553761191;6144.664145829851;6169.167162433194;6171.6297022178815;6181.412765787243;6190.164327815687;5874.089516125007;5878.882422351615;5887.61730487517;5901.527877769854;5914.911276330772;5736.885262294814;5854.619449735754;5865.384134665798;5884.32330282023;5920.023196172583;5759.481646651821;5820.417958204146;5827.3007239131575;5830.737616807339;5860.531157507475;5794.159846822103;5799.971000145141;5800.4489573490455;5802.970985145047;5816.563757718103;5561.026820306511;5566.666000040011;5729.369769325454;5747.9137812932;5775.422457754359;5885.293764748258;5908.970455147697;5912.083627037816;5931.317898441562;5943.108533719977;5750.424957504216;5754.942450575608;5758.222639943554;5760.222369979999;5767.711614419179 04:47:42 INFO - PROCESS | 6759 | |1;ext.html;576.8788554403575;596.561527277451;619.5477301569854;619.6777675608683;652.4226059936955;35227.11932201693;38444.61555384445;47372.763136184316;47993.28010079843;48291.27563086548;49834.25248621274;50268.49731502684;50311.24533132006;50380.4885853425;50428;9296.90703092969;9448.81102377952;9449.291303152257;9455.858162127573;9462.053794620551;8457.154284571525;8680.826383472328;10914.727131821688;11285.097192224603;11298.661040168767;11433.485493152786;11516.539338426453;11519.596814111508;11530.942345288304;11532.135089868252;11235.101191904629;11273.041791447737;11285.435728213597;11316.434178291134;11321.377324247163;10980;11115.277506962102;11129.666110016713;11148.386838723867;11150.944245278723;46642.30036549454;46946.53053469456;47008.52991470076;47112.29331560029;47114.76442617765;3684.299983003221;4050.784764570617;4249.936250956238;4343.305071188594;4427.867163985085;35013.82493087544;35075.82462087673;35583.542238669186;35739.64260357389;36093.097672558135;4787.0904528139545;4850.53919877611;4913.091078150518;4931.112399768041;4996.100701873661;380434;383413;387392;387706;388138;69008;69228;69450;69458.3054169462;69505;40522;41388.37917431241;41480;43079;72909.63545182346;19856.205751770056;20043.799562004486;20054.89972550157;20057.598848022957;20079.59840803205;13873.37569809361;14312.928435357757;15155.318010689545;15262.542123736306;15266.313015914311;20631.896840515703;21174.364769056952;21241.68137477908;21858.234961776263;21921.561568768535;7318.121825380988;7439.181690014094;7469.962650186714;7538.246175382527;7667.156612772591;1029.994850025745;1040.2239869274485;1075.424647813416;1085.3596378136938;1089.8746644135867;34329.31341373159;34907.82546087304;35092.12269693278;35151.82424087863;35322.64677353245;1131.6995417413796;1154.584349634131;1169.128998895828;1180.6363649984282;1261.7539579781856;17752.91123544374;17885.783476914567;17986.19062142207;19151.52121196981;19276.42170734852;605735;609404;609536.6662166892;610935;611774;65553.67223163949;66173.66913165404;66278.33721662722;66294.33705662882;66317.33682663208;1298.5130576033987;1374.5463996881006;1376.167418711685;1446.8191476065492;1460.116629439174;30901;33735.32529349448;34899.825500872335;34908.82545587256;34909.65090349064;54609;55179;55356.44643553674;55373.16940245819;55378.446215537326;432234;436401;436612;436657;437809;32019.35961280808;33086;33267.66732332743;34523.13692157716;34552.30895382129;52357.73821131022;52370.73814631055;52470.73764631152;52974.73512632412;53023.73488132535;43332.566674332855;44594.3310850331;44947.77526112348;45471.31793023174;45974.54025459837;43224.56775432205;44528.33207501955;44616.33075503935;45604.771976139906;45804.31293530533;44085.559144408144;45833.77083114563;47098.29352559783;47173;48184;37967;40020.39969400403;40366.39450408305;41322;41354;29742;30086.849565752033;30162.396752064396;30190.396192075597;30485.847570762005 04:47:42 INFO - PROCESS | 6759 | |2;jquery.html;399.8640462242837;440.1175642836115;440.79723327269454;443.55201246740785;448.0635471863806;17111.657766844655;19602.803971960293;20434.386968390936;21053.894730526325;21183.788162118413;19573.510662233417;20547.38357849263;20664.17343306266;20813.791862081376;20842.58314833706;5917.497012753921;5927.377625349341;6162.568620196588;6174.10475481055;6181.505479561647;6025.246844144482;6085.817425477243;6113.480354169901;6121.540884433663;6121.755129794803;5862.091375836731;6015.608985415955;6080.270367555878;6080.665563394012;6084.939150608504;6007.789727359544;6029.60807547508;6050.727717252734;6057.3336932937345;6057.485113765335;5436.456354364532;5447.972760136174;5451.3458384994055;5482.725863706799;5490.423505531941;24165.879170604036;24318.635220471708;24686.629700554506;24771;25273.241802745946;2506.5738824399896;2601.0506165249735;2607.739226077396;3116.7506599472213;3220.0983724557163;15357.692846143127;15397.538073857802;15411.460598878986;15417.15205663688;15620.297086631128;1966.9606607867906;2039.898005099739;2041.2651445479617;2046.6623007203793;2092.424583239606;86797.56601217017;86829.56585217097;87100;87157;88097.11902880954;10231.488425578691;11246.268992515461;11349.092072634334;11964.461599228056;11977.880221197676;15148.545543633709;15777.921110394374;15847.762283565755;15994.520164395086;16529.752053719203;8743.475391476532;8800.8679869802;8975.551222438851;8993.775155621162;9047.909520904706;8642.265407440438;8652.295933599988;8787.956060219658;8921.598528066123;8999.460032398078;6866.896996545056;7026.718931242693;7034.366906978396;7068.293170682993;7094.50338476312;2058.361907808584;2116.565486536152;2122.299641118458;2131.9553418824976;2137.6366017777013;952.3999880075646;1044.6500422358595;1066.2429674930731;1085.8696956365286;1097.7036200225893;19539.609207815764;19568.3151089714;20301.289454869304;20834.10380069455;21200;1038.1746511523356;1069.6737495063924;1076.5855145768935;1080.9729756756144;1090.923635345534;10748.247622666338;11706.356150411639;11845.763084738257;11882.821757673642;11951.641450756491;440899;479415;492471;505842;508529;78423.21576784273;80358.19641803624;80510;81392;82738.58630706929;1052.9631462898762;1084.3764835219781;1108.866935967689;1112.5382966069153;1112.5994641928899;18158;18353.724694129865;18657.38706714098;18753.71869421987;19022.334218302294;45357;45575.77212113918;46148;46290.748021699364;46786.76606616945;61118.388816113045;61156;61805.69097154486;62543.374566253755;63003;8918.286537076889;9200.125988031126;9367.953160234156;9375.718728438158;9441.331295635888;12949.287789171685;13403;13545.661358466117;13614.591562253148;13670.043096983514;11867.762644746885;11979.460924258256;12056;12086.093542984487;12255.509779608716;11564.884351156381;11699.356535390636;11807.822882656596;11916.106292028306;12219.389030548615;11613.129015323715;11650;11984.700382490508;12060.698482538006;12267.938660306641;8935.240504557185;9205.401648892801;9260.30547708938;9367.344285899922;9526.761830954281;11815.940920295343;12128.454219560317;12152.696182595153;12250.203736757083;12267.815982760445 04:47:42 INFO - PROCESS | 6759 | |3;mootools.html;1369.7808350663895;1505.7515509940856;1546.7834503169554;1668.5911951571866;1848.6395152945165;8189.959050204748;9079.500627465486;9407.38851974623;9432.481213533261;9683.61265549379;9446.905530944705;9521.428714277148;9522.476263805487;9523.190528805042;9537;2431.9027238910467;2456.5823809952262;2461.7661322174454;2462.642916777066;2463.4949835283815;2405.891734871926;2498.4503409249937;2523.709773376058;2524.684414448194;2528.329992551975;2414.408469924871;2426.9635955460594;2466.1984854922134;2470.987645061772;2474.604063349864;2401.939951501209;2418.3954011497126;2427.0291883246664;2462.089027059981;2464.544059349026;2378.6432035194684;2401.219603628828;2401.3156250468705;2406.7833894949363;2422.915197968063;9285.767855803591;9326.813463730756;9328.580213890391;9331.860022099674;9416.10546998041;5300.787968481256;5507.008738427082;5508.779648814043;5521.365043020064;5521.723913804334;3458.4639380896;3495.8252087395526;3535.4696795480627;3541.043918142087;3562.287542491512;1171.8183681529376;1185.0697202695928;1204.7168915304896;1206.294317824074;1219.1161407979143;12732.745345093046;12772.61682149537;12785.488580456771;12848.935755321258;12857.421416036208;4544.136614043321;4713.858584242478;4823.879403014953;4850.296706977469;4857.635677324215;10023.498825058718;10045.497725113715;10047.949760251153;10048.648297309559;10061.446620435947;5383.111786555174;5388.380336261302;5389;5395.703236321961;5396.865078373072;4522.4573051234065;4659.510751371057;5002.59979201663;5029.572486338702;5093.643444958817;4004.2191772604647;4052.6555242804693;4161.625453709181;4163.437935878678;4194;1193.1051711216587;1208.5649166300125;1225.0322245426191;1226.3806777577445;1233.6237447578396;883.2757139145967;892.2505095719627;972.8151651186394;983.8425851863667;1029.9433531155858;6139.6009259398;6307.148534947815;6319.526035547352;6517.543771936013;6523.956167013255;977.9511024448749;1018.4602160854758;1033.4471057983942;1044.2429238801808;1053.9683809485728;4507.414036175276;4612.884677883079;4618.907621847544;4636.35091087248;4637.837675681402;11619.360935148648;12007.519699211934;12087.033037357167;12124.27254364741;12156.635300940987;11797.233179843282;11830.112741544419;11938.164328497096;11947.10396720232;11988.940055299669;8821.823563528693;8845.557722113868;8851.778705532284;8953.462792232618;8970;6427.357264273535;6523.64119973406;6540.640264785482;6609;6624.138861947916;10233.130183934449;10558.577656893638;10634.521446534769;10744;10938.398388088732;10510.316829406063;10572.41851698164;10825;10836.67489975302;10943.562257509611;7678.347340476123;7841.647125879464;8619.224269815746;8802.647894084164;9037.50293733851;9008.369414140947;9290.256779457492;9290.396124251902;9293.814123717622;9293.953530232306;8048.678052878054;8126.471779334324;8136.918630813616;8163.79590510242;8303.252707256375;7846.686132554634;7919.604019798871;8016.799580010313;8044.35645148375;8085.8787118192095;7221.386182174364;7291.380232680282;7383.630818458948;7391.556506609621;7413.888791668021;5490.121580547093;5523.751431185687;5660.037793575019;5706.657600543981;5716.142578613241;5326.334208223972;5569.136783798518;5599.524040456607;5643.661380317194;5668.829935101953 04:47:42 INFO - PROCESS | 6759 | |4;prototype.html;360.62314880949407;362.4165094198341;365.42080801928944;366.46862050027465;373.9663430291273;5633.690147041911;5682.863427314533;5687.886242275157;5697.145428185774;5715;5331.253624492574;5521.199426083215;5639.351474580424;5641.52047075998;5648.717564121798;4324.329728892027;4371.497277813044;4385.605295523403;4390.100029493946;4402.207602631525;4376.358865425465;4388.736675799446;4390.517043125254;4392.3631073494325;4401.36180253863;4352.499462561798;4361.193179261847;4363.778142120212;4366.454193225847;4372.89067773305;4261.531231564525;4339.6528277738025;4347.608715215646;4354.673399495019;4355.760433176174;3898.200868821885;3907.765534067965;3913.4912461380122;3917.4711413959035;3919.7256192066566;5380.5964552658315;5381.300430943991;5394.109971854639;5404.648697834628;5421.72589441481;4914.6147736298435;4977.377827771528;4998.650094493387;5005.749712514396;5047.293378926953;5794.82615521535;5795.739191736382;5795.971020144872;5813.215215945836;5825.300963884361;4307.763073030982;4315.741055536647;4317;4319.049809042006;4341.80461879213;5667.8583035424035;5808.912866307009;5842.707864606795;5995.61028533144;6039.8188054358;4266.146770645883;4365.519792822787;4367.519572846984;4373.387725718402;4385.188740083095;4409.2724700424715;4427.512973572904;4438.245498265303;4460.219461594273;4462.576055274743;4675.22858728306;4766.833160839354;4832.4684284728655;5005.224190250517;5012.298278241049;5718.714064296769;5742;5763.962486752424;5765.54067559461;5800.796972106041;1517.9848201518064;1541.4990128208153;1545.7526795712856;1548.2258870564717;1552.5031989763393;999.5252255178732;1015.3806178231273;1016.3393794033938;1024.9955044056867;1035.5554002167084;4946.554810067111;4969.826056088092;4973.751312434364;4973.806286491216;4983.825566105242;5194.818181363709;5198.636095473281;5206.875874475285;5215.217717342353;5239.633225674166;4622.768861556909;4945.208766597327;4961.925571116436;4968.677035992649;4972.801087956514;2766.5020296346447;2778.7776977841722;2786.749192572678;2788.6235358226786;2811;5295.258663787073;5319.069162896478;5340.412554618989;5357.963973689973;5361.35663720356;5312.707801070978;5699.686517241592;6092.3603021682975;6139.907901381483;6148.1392605035335;6129.835331287041;6135.8341915036;6137.539684523678;6175.475084617858;6175.5677102602385;5679.432056794288;5953;5972.940270597239;5999.940000599938;6015.87968240624;5918.763249470146;5942.811437712388;5944.1083837423;5958;5958.672273025025;5880.970595146997;5968.194293770285;6004.939950600438;6085.4523092921845;6107.27456251695;4310.37499562568;4325.567443255649;4329.350597410414;4346.217680817484;4348.499922508826;5768.394318596487;5926.940730592639;5992.700364981821;5995.250593675791;5997.910031349446;5848.122781582797;5850.063989761617;5851.64890106595;5853.590248682722;5871.7651293949475;5814.098814683731;5815.5929084963645;5816.796412125555;5840.474357307863;5843.065109582616;5511.448855114457;5750.108733146369;5763.538916886723;5794.62334948227;5798.478136967693;5856.765729370777;5895.469407753323;5899.292084949833;5910.46805787464;5919.911201331897;5523.204958388459;5741.425857414225;5746.913796293143;5755.482006619257;5774.538036957118 04:47:42 INFO - PROCESS | 6759 | |5;yui.html;788.4638445856815;822.6791551294996;853.8377708235435;873.3144481581959;931.9673811416598;14680.632984175401;16093;16226.107564083986;16233.350665973347;16233.91883040586;14317;14411.207383593897;14418.639534011654;16521.256543455544;17204.139793010363;694.4270976444441;714.1751277274756;821.109096630157;837.6984285657159;850.3367373448689;858.6608289725585;859.2266959736256;860.2472836268265;861.2033868671485;861.3711990247124;728.2208037399984;749.3630414147958;926.6571368593596;927.281356948367;928.0811996123856;898.2813749000806;914.4147745442889;922.8661844032612;922.9261659067258;922.9446233226028;673.2022553274386;673.6968364236113;673.8113328268092;674.2280089297726;675.0448115915973;16255.918720406322;16268.837311626969;16288.674226515403;16292.592685182964;16299.185040747916;14199.36102875373;14750.409983600643;14762.483313084098;14995.625109372244;15018.174000429972;13700.794488082687;13894.096883702527;14015.579532614038;14228.786568201485;14485;4248.808803603845;4274.2092712848225;4299.957000429987;4347.956520434786;4496;88410;89334;90083;90688;91638;11560.94219528897;12132.939335303266;12288;12447.066470014784;12487.425855908989;22136.66794998076;22212.555748884934;22253;22271.55456890853;22297.219597314015;9270.67552635668;9449.858252126223;9451.432914025181;9524.714258572254;9673.9032609673;4885.2427873679635;5122.410922743858;5185.948140518546;5199.714015729096;5279.47205279469;3461.2558299965626;3570.946435803465;3583.0863129902227;3601.981990090033;3602.6757591816863;661.256086902235;666.3336663336663;670.8457054877406;675.7803713793037;690.0167261652126;674.2044387622652;793.8848866914265;797.5453991225062;802.5906787538397;806.2542148512603;10339.689809305582;10590.894091059145;10602.25784195099;10875.293105948089;11003.779924401628;700.457145712067;776.4836383804819;791.1574173505198;793.6586968861159;823.6993497373415;5263.657862239019;5341.866453338697;5375.16684913839;5376.247325374451;5534.861628459321;131571.3421432906;135039;136423;140190;141578;115599.42200288945;117885;118247;118254.40872795582;120838.3958080204;59115;61062;62419;78808;98595.99999999856;10029.398236105857;10057.49712514386;10365;10366.06705396518;10522.263441559324;58358.4164158353;59640.70179649074;60469.395306046375;60731;61181.69409152926;84541;88002;88920;89104;90856;11985.460654270404;12249.69375765613;12283.69290767738;12534.749305013665;12910.80633790475;14709.338079786221;14833.33250003774;14966.850331496546;15240.085594864344;15346;13575.321233938066;13630.045896787526;13707.383167757276;13857.37641806101;14149.151050936976;13717.382717777924;13993.650158745706;14334.426622934965;14646.048006879519;15193.316300766272;12570.685732856751;12900.806487902877;13362.599122026355;13382;14071.155730656194;10489.160867130458;10687.625933092304;10695.358278503314;10721.571137154426;10959.06847917936;10990.560377585127;11224.887751122711;11302.943485282522;11313.151513636674;11417.1437142213 04:47:42 INFO - PROCESS | 6759 | __end_tp_report 04:47:42 INFO - PROCESS | 6759 | __start_cc_report 04:47:42 INFO - PROCESS | 6759 | _x_x_mozilla_cycle_collect,850 04:47:42 INFO - PROCESS | 6759 | __end_cc_report 04:47:42 INFO - PROCESS | 6759 | __startTimestamp1478436462843__endTimestamp 04:47:42 INFO - PROCESS | 6759 | 04:47:42 INFO - PROCESS | 6759 | ------- Summary: start ------- 04:47:42 INFO - PROCESS | 6759 | Number of tests: 6 04:47:42 INFO - PROCESS | 6759 | 04:47:42 INFO - PROCESS | 6759 | [#0] dojo.html Cycles:165 Average:5514.60 Median:5758.22 stddev:631.54 (11.0%) stddev-sans-first:604.39 04:47:42 INFO - PROCESS | 6759 | Values: 3099.3 3135.4 3142.6 3157.2 3175.0 5482.2 5509.3 5531.6 5554.8 5583.9 5505.2 5585.6 5593.6 5597.5 5597.6 5028.4 5054.1 5069.9 5110.5 5146.0 5005.4 5019.4 5084.4 5140.4 5147.6 4978.4 5009.2 5011.8 5033.8 5043.4 5043.8 5082.0 5134.5 5144.0 5149.8 4832.9 5006.2 5026.6 5062.6 5086.0 5344.9 5351.5 5385.8 5419.7 5435.0 5835.6 5848.9 5904.0 5912.9 5912.9 5794.3 5944.8 5957.9 5958.6 5962.9 5465.2 5512.5 5563.2 5575.8 5647.2 6086.1 6129.7 6130.0 6132.5 6137.5 5871.6 5872.5 5886.4 5888.3 5890.8 5835.6 5845.8 5871.9 5905.4 5915.1 5931.3 5931.9 5932.1 5937.9 5959.8 5885.4 5934.2 5950.4 5951.6 5979.1 4474.9 4505.1 4506.9 4518.4 4556.5 4035.2 4061.9 4068.5 4083.2 4092.6 5681.8 5683.4 5688.8 5689.1 5690.0 5808.1 5857.1 5883.9 5962.3 5965.7 5635.5 5700.9 5703.3 5710.3 5736.5 5173.1 5173.6 5175.7 5182.0 5182.0 5978.9 6084.5 6111.2 6123.2 6124.9 5860.8 5867.5 5941.1 5944.3 6017.8 6144.7 6169.2 6171.6 6181.4 6190.2 5874.1 5878.9 5887.6 5901.5 5914.9 5736.9 5854.6 5865.4 5884.3 5920.0 5759.5 5820.4 5827.3 5830.7 5860.5 5794.2 5800.0 5800.4 5803.0 5816.6 5561.0 5566.7 5729.4 5747.9 5775.4 5885.3 5909.0 5912.1 5931.3 5943.1 5750.4 5754.9 5758.2 5760.2 5767.7 04:47:42 INFO - PROCESS | 6759 | 04:47:42 INFO - PROCESS | 6759 | [#1] ext.html Cycles:180 Average:65436.48 Median:40922.00 stddev:128980.76 (315.2%) stddev-sans-first:129250.65 04:47:42 INFO - PROCESS | 6759 | Values: 576.9 596.6 619.5 619.7 652.4 35227.1 38444.6 47372.8 47993.3 48291.3 49834.3 50268.5 50311.2 50380.5 50428.0 9296.9 9448.8 9449.3 9455.9 9462.1 8457.2 8680.8 10914.7 11285.1 11298.7 11433.5 11516.5 11519.6 11530.9 11532.1 11235.1 11273.0 11285.4 11316.4 11321.4 10980.0 11115.3 11129.7 11148.4 11150.9 46642.3 46946.5 47008.5 47112.3 47114.8 3684.3 4050.8 4249.9 4343.3 4427.9 35013.8 35075.8 35583.5 35739.6 36093.1 4787.1 4850.5 4913.1 4931.1 4996.1 380434.0 383413.0 387392.0 387706.0 388138.0 69008.0 69228.0 69450.0 69458.3 69505.0 40522.0 41388.4 41480.0 43079.0 72909.6 19856.2 20043.8 20054.9 20057.6 20079.6 13873.4 14312.9 15155.3 15262.5 15266.3 20631.9 21174.4 21241.7 21858.2 21921.6 7318.1 7439.2 7470.0 7538.2 7667.2 1030.0 1040.2 1075.4 1085.4 1089.9 34329.3 34907.8 35092.1 35151.8 35322.6 1131.7 1154.6 1169.1 1180.6 1261.8 17752.9 17885.8 17986.2 19151.5 19276.4 605735.0 609404.0 609536.7 610935.0 611774.0 65553.7 66173.7 66278.3 66294.3 66317.3 1298.5 1374.5 1376.2 1446.8 1460.1 30901.0 33735.3 34899.8 34908.8 34909.7 54609.0 55179.0 55356.4 55373.2 55378.4 432234.0 436401.0 436612.0 436657.0 437809.0 32019.4 33086.0 33267.7 34523.1 34552.3 52357.7 52370.7 52470.7 52974.7 53023.7 43332.6 44594.3 44947.8 45471.3 45974.5 43224.6 44528.3 44616.3 45604.8 45804.3 44085.6 45833.8 47098.3 47173.0 48184.0 37967.0 40020.4 40366.4 41322.0 41354.0 29742.0 30086.8 30162.4 30190.4 30485.8 04:47:42 INFO - PROCESS | 6759 | 04:47:42 INFO - PROCESS | 6759 | [#2] jquery.html Cycles:180 Average:29565.46 Median:24502.63 stddev:79999.30 (326.5%) stddev-sans-first:80193.74 04:47:42 INFO - PROCESS | 6759 | Values: 399.9 440.1 440.8 443.6 448.1 17111.7 19602.8 20434.4 21053.9 21183.8 19573.5 20547.4 20664.2 20813.8 20842.6 5917.5 5927.4 6162.6 6174.1 6181.5 6025.2 6085.8 6113.5 6121.5 6121.8 5862.1 6015.6 6080.3 6080.7 6084.9 6007.8 6029.6 6050.7 6057.3 6057.5 5436.5 5448.0 5451.3 5482.7 5490.4 24165.9 24318.6 24686.6 24771.0 25273.2 2506.6 2601.1 2607.7 3116.8 3220.1 15357.7 15397.5 15411.5 15417.2 15620.3 1967.0 2039.9 2041.3 2046.7 2092.4 86797.6 86829.6 87100.0 87157.0 88097.1 10231.5 11246.3 11349.1 11964.5 11977.9 15148.5 15777.9 15847.8 15994.5 16529.8 8743.5 8800.9 8975.6 8993.8 9047.9 8642.3 8652.3 8788.0 8921.6 8999.5 6866.9 7026.7 7034.4 7068.3 7094.5 2058.4 2116.6 2122.3 2132.0 2137.6 952.4 1044.7 1066.2 1085.9 1097.7 19539.6 19568.3 20301.3 20834.1 21200.0 1038.2 1069.7 1076.6 1081.0 1090.9 10748.2 11706.4 11845.8 11882.8 11951.6 440899.0 479415.0 492471.0 505842.0 508529.0 78423.2 80358.2 80510.0 81392.0 82738.6 1053.0 1084.4 1108.9 1112.5 1112.6 18158.0 18353.7 18657.4 18753.7 19022.3 45357.0 45575.8 46148.0 46290.7 46786.8 61118.4 61156.0 61805.7 62543.4 63003.0 8918.3 9200.1 9368.0 9375.7 9441.3 12949.3 13403.0 13545.7 13614.6 13670.0 11867.8 11979.5 12056.0 12086.1 12255.5 11564.9 11699.4 11807.8 11916.1 12219.4 11613.1 11650.0 11984.7 12060.7 12267.9 8935.2 9205.4 9260.3 9367.3 9526.8 11815.9 12128.5 12152.7 12250.2 12267.8 04:47:42 INFO - PROCESS | 6759 | 04:47:42 INFO - PROCESS | 6759 | [#3] mootools.html Cycles:180 Average:6142.62 Median:4637.09 stddev:3507.26 (75.6%) stddev-sans-first:3498.75 04:47:42 INFO - PROCESS | 6759 | Values: 1369.8 1505.8 1546.8 1668.6 1848.6 8190.0 9079.5 9407.4 9432.5 9683.6 9446.9 9521.4 9522.5 9523.2 9537.0 2431.9 2456.6 2461.8 2462.6 2463.5 2405.9 2498.5 2523.7 2524.7 2528.3 2414.4 2427.0 2466.2 2471.0 2474.6 2401.9 2418.4 2427.0 2462.1 2464.5 2378.6 2401.2 2401.3 2406.8 2422.9 9285.8 9326.8 9328.6 9331.9 9416.1 5300.8 5507.0 5508.8 5521.4 5521.7 3458.5 3495.8 3535.5 3541.0 3562.3 1171.8 1185.1 1204.7 1206.3 1219.1 12732.7 12772.6 12785.5 12848.9 12857.4 4544.1 4713.9 4823.9 4850.3 4857.6 10023.5 10045.5 10047.9 10048.6 10061.4 5383.1 5388.4 5389.0 5395.7 5396.9 4522.5 4659.5 5002.6 5029.6 5093.6 4004.2 4052.7 4161.6 4163.4 4194.0 1193.1 1208.6 1225.0 1226.4 1233.6 883.3 892.3 972.8 983.8 1029.9 6139.6 6307.1 6319.5 6517.5 6524.0 978.0 1018.5 1033.4 1044.2 1054.0 4507.4 4612.9 4618.9 4636.4 4637.8 11619.4 12007.5 12087.0 12124.3 12156.6 11797.2 11830.1 11938.2 11947.1 11988.9 8821.8 8845.6 8851.8 8953.5 8970.0 6427.4 6523.6 6540.6 6609.0 6624.1 10233.1 10558.6 10634.5 10744.0 10938.4 10510.3 10572.4 10825.0 10836.7 10943.6 7678.3 7841.6 8619.2 8802.6 9037.5 9008.4 9290.3 9290.4 9293.8 9294.0 8048.7 8126.5 8136.9 8163.8 8303.3 7846.7 7919.6 8016.8 8044.4 8085.9 7221.4 7291.4 7383.6 7391.6 7413.9 5490.1 5523.8 5660.0 5706.7 5716.1 5326.3 5569.1 5599.5 5643.7 5668.8 04:47:42 INFO - PROCESS | 6759 | 04:47:42 INFO - PROCESS | 6759 | [#4] prototype.html Cycles:180 Average:4825.18 Median:5315.89 stddev:1396.91 (26.3%) stddev-sans-first:1360.04 04:47:42 INFO - PROCESS | 6759 | Values: 360.6 362.4 365.4 366.5 374.0 5633.7 5682.9 5687.9 5697.1 5715.0 5331.3 5521.2 5639.4 5641.5 5648.7 4324.3 4371.5 4385.6 4390.1 4402.2 4376.4 4388.7 4390.5 4392.4 4401.4 4352.5 4361.2 4363.8 4366.5 4372.9 4261.5 4339.7 4347.6 4354.7 4355.8 3898.2 3907.8 3913.5 3917.5 3919.7 5380.6 5381.3 5394.1 5404.6 5421.7 4914.6 4977.4 4998.7 5005.7 5047.3 5794.8 5795.7 5796.0 5813.2 5825.3 4307.8 4315.7 4317.0 4319.0 4341.8 5667.9 5808.9 5842.7 5995.6 6039.8 4266.1 4365.5 4367.5 4373.4 4385.2 4409.3 4427.5 4438.2 4460.2 4462.6 4675.2 4766.8 4832.5 5005.2 5012.3 5718.7 5742.0 5764.0 5765.5 5800.8 1518.0 1541.5 1545.8 1548.2 1552.5 999.5 1015.4 1016.3 1025.0 1035.6 4946.6 4969.8 4973.8 4973.8 4983.8 5194.8 5198.6 5206.9 5215.2 5239.6 4622.8 4945.2 4961.9 4968.7 4972.8 2766.5 2778.8 2786.7 2788.6 2811.0 5295.3 5319.1 5340.4 5358.0 5361.4 5312.7 5699.7 6092.4 6139.9 6148.1 6129.8 6135.8 6137.5 6175.5 6175.6 5679.4 5953.0 5972.9 5999.9 6015.9 5918.8 5942.8 5944.1 5958.0 5958.7 5881.0 5968.2 6004.9 6085.5 6107.3 4310.4 4325.6 4329.4 4346.2 4348.5 5768.4 5926.9 5992.7 5995.3 5997.9 5848.1 5850.1 5851.6 5853.6 5871.8 5814.1 5815.6 5816.8 5840.5 5843.1 5511.4 5750.1 5763.5 5794.6 5798.5 5856.8 5895.5 5899.3 5910.5 5919.9 5523.2 5741.4 5746.9 5755.5 5774.5 04:47:42 INFO - PROCESS | 6759 | 04:47:42 INFO - PROCESS | 6759 | [#5] yui.html Cycles:180 Average:22863.42 Median:3516.10 stddev:34355.11 (977.1%) stddev-sans-first:34411.50 04:47:42 INFO - PROCESS | 6759 | Values: 788.5 822.7 853.8 873.3 932.0 14680.6 16093.0 16226.1 16233.4 16233.9 14317.0 14411.2 14418.6 16521.3 17204.1 694.4 714.2 821.1 837.7 850.3 858.7 859.2 860.2 861.2 861.4 728.2 749.4 926.7 927.3 928.1 898.3 914.4 922.9 922.9 922.9 673.2 673.7 673.8 674.2 675.0 16255.9 16268.8 16288.7 16292.6 16299.2 14199.4 14750.4 14762.5 14995.6 15018.2 13700.8 13894.1 14015.6 14228.8 14485.0 4248.8 4274.2 4300.0 4348.0 4496.0 88410.0 89334.0 90083.0 90688.0 91638.0 11560.9 12132.9 12288.0 12447.1 12487.4 22136.7 22212.6 22253.0 22271.6 22297.2 9270.7 9449.9 9451.4 9524.7 9673.9 4885.2 5122.4 5185.9 5199.7 5279.5 3461.3 3570.9 3583.1 3602.0 3602.7 661.3 666.3 670.8 675.8 690.0 674.2 793.9 797.5 802.6 806.3 10339.7 10590.9 10602.3 10875.3 11003.8 700.5 776.5 791.2 793.7 823.7 5263.7 5341.9 5375.2 5376.2 5534.9 131571.3 135039.0 136423.0 140190.0 141578.0 115599.4 117885.0 118247.0 118254.4 120838.4 59115.0 61062.0 62419.0 78808.0 98596.0 10029.4 10057.5 10365.0 10366.1 10522.3 58358.4 59640.7 60469.4 60731.0 61181.7 84541.0 88002.0 88920.0 89104.0 90856.0 11985.5 12249.7 12283.7 12534.7 12910.8 14709.3 14833.3 14966.9 15240.1 15346.0 13575.3 13630.0 13707.4 13857.4 14149.2 13717.4 13993.7 14334.4 14646.0 15193.3 12570.7 12900.8 13362.6 13382.0 14071.2 10489.2 10687.6 10695.4 10721.6 10959.1 10990.6 11224.9 11302.9 11313.2 11417.1 04:47:42 INFO - PROCESS | 6759 | -------- Summary: end -------- 04:47:42 INFO - PROCESS | 6759 | 04:47:43 INFO - TEST-INFO | 6759: exit 0 04:47:43 INFO - TEST-OK | dromaeo_css | took 1113269ms 04:47:43 INFO - TEST-START | kraken 04:47:43 INFO - operating with platform_type : linux_ 04:47:43 INFO - Initialising browser for kraken test... 04:47:43 INFO - TEST-INFO | started process 7085 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp82aV7m/profile http://localhost:55320/getInfo.html) 04:47:47 INFO - PROCESS | 7085 | __metrics Screen width/height:1600/1200 04:47:47 INFO - PROCESS | 7085 | colorDepth:24 04:47:47 INFO - PROCESS | 7085 | Browser inner width/height: 1024/697 04:47:47 INFO - PROCESS | 7085 | __metrics 04:47:48 INFO - PROCESS | 7085 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:47:48 INFO - PROCESS | 7085 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:47:48 INFO - PROCESS | 7085 | console.error: PushService: 04:47:48 INFO - PROCESS | 7085 | stateChangeProcessEnqueue: Error transitioning state 04:47:48 INFO - PROCESS | 7085 | UnknownError 04:47:49 INFO - TEST-INFO | 7085: exit 0 04:47:49 INFO - Browser initialized. 04:47:49 INFO - Running cycle 1/1 for kraken test... 04:47:49 INFO - TEST-INFO | started process 7179 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp82aV7m/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:48:01 INFO - PROCESS | 7179 | RSS: Main: 144977920 04:48:01 INFO - PROCESS | 7179 | 04:48:03 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:55320/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55320/tests/kraken/driver.html?testName=audio-beat-detection) 04:48:04 INFO - PROCESS | 7179 | RSS: Main: 186204160 04:48:04 INFO - PROCESS | 7179 | 04:48:06 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:55320/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55320/tests/kraken/driver.html?testName=audio-dft) 04:48:07 INFO - PROCESS | 7179 | RSS: Main: 176168960 04:48:07 INFO - PROCESS | 7179 | 04:48:10 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:55320/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55320/tests/kraken/driver.html?testName=audio-fft) 04:48:10 INFO - PROCESS | 7179 | RSS: Main: 267665408 04:48:10 INFO - PROCESS | 7179 | 04:48:12 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:55320/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55320/tests/kraken/driver.html?testName=audio-oscillator) 04:48:13 INFO - PROCESS | 7179 | RSS: Main: 173518848 04:48:13 INFO - PROCESS | 7179 | 04:48:15 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:55320/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55320/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:48:16 INFO - PROCESS | 7179 | RSS: Main: 176013312 04:48:16 INFO - PROCESS | 7179 | 04:48:19 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:55320/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55320/tests/kraken/driver.html?testName=imaging-darkroom) 04:48:19 INFO - PROCESS | 7179 | RSS: Main: 211566592 04:48:19 INFO - PROCESS | 7179 | 04:48:23 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:55320/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55320/tests/kraken/driver.html?testName=imaging-desaturate) 04:48:23 INFO - PROCESS | 7179 | RSS: Main: 210006016 04:48:23 INFO - PROCESS | 7179 | 04:48:26 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:55320/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55320/tests/kraken/driver.html?testName=json-parse-financial) 04:48:27 INFO - PROCESS | 7179 | RSS: Main: 210001920 04:48:27 INFO - PROCESS | 7179 | 04:48:28 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:55320/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55320/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:48:29 INFO - PROCESS | 7179 | RSS: Main: 172265472 04:48:29 INFO - PROCESS | 7179 | 04:48:30 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:55320/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55320/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:48:31 INFO - PROCESS | 7179 | RSS: Main: 180523008 04:48:31 INFO - PROCESS | 7179 | 04:48:33 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:55320/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55320/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:48:34 INFO - PROCESS | 7179 | RSS: Main: 188747776 04:48:34 INFO - PROCESS | 7179 | 04:48:36 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:55320/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55320/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:48:37 INFO - PROCESS | 7179 | RSS: Main: 197853184 04:48:37 INFO - PROCESS | 7179 | 04:48:39 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:55320/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55320/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:48:40 INFO - PROCESS | 7179 | RSS: Main: 193994752 04:48:40 INFO - PROCESS | 7179 | 04:48:42 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:55320/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55320/tests/kraken/driver.html?testName=ai-astar) 04:48:42 INFO - PROCESS | 7179 | RSS: Main: 245497856 04:48:42 INFO - PROCESS | 7179 | 04:48:42 INFO - PROCESS | 7179 | __start_tp_report 04:48:42 INFO - PROCESS | 7179 | _x_x_mozilla_page_load 04:48:42 INFO - PROCESS | 7179 | _x_x_mozilla_page_load_details 04:48:42 INFO - PROCESS | 7179 | |i|pagename|runs| 04:48:42 INFO - PROCESS | 7179 | |0;ai-astar;108;110;112;109;111;113;110;111;109;111 04:48:42 INFO - PROCESS | 7179 | |1;audio-beat-detection;150;117;120;165;117;121;121;115;156;117 04:48:42 INFO - PROCESS | 7179 | |2;audio-dft;161;161;160;160;162;160;163;162;160;162 04:48:42 INFO - PROCESS | 7179 | |3;audio-fft;73;76;76;110;75;76;76;76;104;75 04:48:42 INFO - PROCESS | 7179 | |4;audio-oscillator;82;82;117;83;82;82;100;81;82;111 04:48:42 INFO - PROCESS | 7179 | |5;imaging-gaussian-blur;117;117;117;116;118;116;117;117;116;116 04:48:42 INFO - PROCESS | 7179 | |6;imaging-darkroom;148;149;149;149;151;147;149;148;147;149 04:48:42 INFO - PROCESS | 7179 | |7;imaging-desaturate;96;95;95;95;96;95;94;95;96;95 04:48:42 INFO - PROCESS | 7179 | |8;json-parse-financial;50;49;49;49;49;48;49;50;49;50 04:48:42 INFO - PROCESS | 7179 | |9;json-stringify-tinderbox;59;61;58;58;59;58;58;58;62;59 04:48:42 INFO - PROCESS | 7179 | |10;stanford-crypto-aes;73;74;73;73;75;74;79;73;73;72 04:48:42 INFO - PROCESS | 7179 | |11;stanford-crypto-ccm;129;124;120;118;119;120;127;122;157;121 04:48:42 INFO - PROCESS | 7179 | |12;stanford-crypto-pbkdf2;153;159;152;153;155;153;152;152;153;150 04:48:42 INFO - PROCESS | 7179 | |13;stanford-crypto-sha256-iterative;62;63;66;67;62;64;62;61;65;61 04:48:42 INFO - PROCESS | 7179 | __end_tp_report 04:48:42 INFO - PROCESS | 7179 | __start_cc_report 04:48:42 INFO - PROCESS | 7179 | _x_x_mozilla_cycle_collect,1602 04:48:42 INFO - PROCESS | 7179 | __end_cc_report 04:48:42 INFO - PROCESS | 7179 | __startTimestamp1478436522459__endTimestamp 04:48:42 INFO - PROCESS | 7179 | 04:48:42 INFO - PROCESS | 7179 | ------- Summary: start ------- 04:48:42 INFO - PROCESS | 7179 | Number of tests: 14 04:48:42 INFO - PROCESS | 7179 | 04:48:42 INFO - PROCESS | 7179 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:1.51 (1.4%) stddev-sans-first:1.32 04:48:42 INFO - PROCESS | 7179 | Values: 108.0 110.0 112.0 109.0 111.0 113.0 110.0 111.0 109.0 111.0 04:48:42 INFO - PROCESS | 7179 | 04:48:42 INFO - PROCESS | 7179 | [#1] audio-beat-detection Cycles:10 Average:129.90 Median:121.00 stddev:19.13 (15.8%) stddev-sans-first:18.86 04:48:42 INFO - PROCESS | 7179 | Values: 150.0 117.0 120.0 165.0 117.0 121.0 121.0 115.0 156.0 117.0 04:48:42 INFO - PROCESS | 7179 | 04:48:42 INFO - PROCESS | 7179 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.50 stddev:1.10 (0.7%) stddev-sans-first:1.17 04:48:42 INFO - PROCESS | 7179 | Values: 161.0 161.0 160.0 160.0 162.0 160.0 163.0 162.0 160.0 162.0 04:48:42 INFO - PROCESS | 7179 | 04:48:42 INFO - PROCESS | 7179 | [#3] audio-fft Cycles:10 Average:81.70 Median:76.00 stddev:13.44 (17.7%) stddev-sans-first:13.88 04:48:42 INFO - PROCESS | 7179 | Values: 73.0 76.0 76.0 110.0 75.0 76.0 76.0 76.0 104.0 75.0 04:48:42 INFO - PROCESS | 7179 | 04:48:42 INFO - PROCESS | 7179 | [#4] audio-oscillator Cycles:10 Average:90.20 Median:82.00 stddev:13.82 (16.9%) stddev-sans-first:14.34 04:48:42 INFO - PROCESS | 7179 | Values: 82.0 82.0 117.0 83.0 82.0 82.0 100.0 81.0 82.0 111.0 04:48:42 INFO - PROCESS | 7179 | 04:48:42 INFO - PROCESS | 7179 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 04:48:42 INFO - PROCESS | 7179 | Values: 117.0 117.0 117.0 116.0 118.0 116.0 117.0 117.0 116.0 116.0 04:48:42 INFO - PROCESS | 7179 | 04:48:42 INFO - PROCESS | 7179 | [#6] imaging-darkroom Cycles:10 Average:148.60 Median:149.00 stddev:1.17 (0.8%) stddev-sans-first:1.22 04:48:42 INFO - PROCESS | 7179 | Values: 148.0 149.0 149.0 149.0 151.0 147.0 149.0 148.0 147.0 149.0 04:48:42 INFO - PROCESS | 7179 | 04:48:42 INFO - PROCESS | 7179 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 04:48:42 INFO - PROCESS | 7179 | Values: 96.0 95.0 95.0 95.0 96.0 95.0 94.0 95.0 96.0 95.0 04:48:42 INFO - PROCESS | 7179 | 04:48:42 INFO - PROCESS | 7179 | [#8] json-parse-financial Cycles:10 Average:49.20 Median:49.00 stddev:0.63 (1.3%) stddev-sans-first:0.60 04:48:42 INFO - PROCESS | 7179 | Values: 50.0 49.0 49.0 49.0 49.0 48.0 49.0 50.0 49.0 50.0 04:48:42 INFO - PROCESS | 7179 | 04:48:42 INFO - PROCESS | 7179 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:59.00 stddev:1.41 (2.4%) stddev-sans-first:1.50 04:48:42 INFO - PROCESS | 7179 | Values: 59.0 61.0 58.0 58.0 59.0 58.0 58.0 58.0 62.0 59.0 04:48:42 INFO - PROCESS | 7179 | 04:48:42 INFO - PROCESS | 7179 | [#10] stanford-crypto-aes Cycles:10 Average:73.90 Median:73.50 stddev:1.97 (2.7%) stddev-sans-first:2.06 04:48:42 INFO - PROCESS | 7179 | Values: 73.0 74.0 73.0 73.0 75.0 74.0 79.0 73.0 73.0 72.0 04:48:42 INFO - PROCESS | 7179 | 04:48:42 INFO - PROCESS | 7179 | [#11] stanford-crypto-ccm Cycles:10 Average:125.70 Median:123.00 stddev:11.55 (9.4%) stddev-sans-first:12.19 04:48:42 INFO - PROCESS | 7179 | Values: 129.0 124.0 120.0 118.0 119.0 120.0 127.0 122.0 157.0 121.0 04:48:42 INFO - PROCESS | 7179 | 04:48:42 INFO - PROCESS | 7179 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.20 Median:153.00 stddev:2.39 (1.6%) stddev-sans-first:2.54 04:48:42 INFO - PROCESS | 7179 | Values: 153.0 159.0 152.0 153.0 155.0 153.0 152.0 152.0 153.0 150.0 04:48:42 INFO - PROCESS | 7179 | 04:48:42 INFO - PROCESS | 7179 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.30 Median:63.50 stddev:2.11 (3.3%) stddev-sans-first:2.19 04:48:42 INFO - PROCESS | 7179 | Values: 62.0 63.0 66.0 67.0 62.0 64.0 62.0 61.0 65.0 61.0 04:48:42 INFO - PROCESS | 7179 | -------- Summary: end -------- 04:48:42 INFO - PROCESS | 7179 | 04:48:43 INFO - TEST-INFO | 7179: exit 0 04:48:43 INFO - TEST-OK | kraken | took 59841ms 04:48:43 INFO - SUITE-END | took 1173s 04:48:43 INFO - Completed test suite (00:19:33) 04:48:43 INFO - javascript benchmark 04:48:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3099.318150006998, 3135.3885992231526, 3142.6228852537693, 3157.1633517117953, 3174.9523757143647, 5482.177673348995, 5509.33887933448, 5531.585131115173, 5554.833354999347, 5583.888322233553, 5505.229267902487, 5585.553155747541, 5593.580481463886, 5597.496225339719, 5597.580181486394, 5028.371453568295, 5054.115529782273, 5069.923951140718, 5110.463401342861, 5146.0222557714105, 5005.449400565934, 5019.372578427697, 5084.440711521748, 5140.357455318085, 5147.639665223436, 4978.377702787152, 5009.2486127080865, 5011.84964451067, 5033.798648054073, 5043.39479262489, 5043.773030213631, 5081.974590127063, 5134.460881607452, 5143.996920600484, 5149.794008239666, 4832.8791780205775, 5006.199008158714, 5026.648134630577, 5062.594992400599, 5086.0, 5344.866378340573, 5351.545118664918, 5385.838424847261, 5419.674819510842, 5435.0, 5835.620684655484, 5848.912266316009, 5904.025835737141, 5912.852178695524, 5912.911306330366, 5794.304683437972, 5944.821655350346, 5957.940420595782, 5958.6424814511265, 5962.910556341659, 5465.180222966547, 5512.503874651261, 5563.221149039137, 5575.776968921279, 5647.209390685307, 6086.117512960596, 6129.693515324216, 6129.96935015331, 6132.50939924805, 6137.508999280047, 5871.55963302754, 5872.471477567039, 5886.381929897385, 5888.263967004124, 5890.793822216202, 5835.591508594358, 5845.766169353178, 5871.911921321183, 5905.350411454737, 5915.142304365843, 5931.288245410578, 5931.940680593225, 5932.110183472513, 5937.910931336033, 5959.7914073008105, 5885.411458854166, 5934.228550288435, 5950.404959504015, 5951.553633477506, 5979.073243647242, 4474.865754027319, 4505.098980203907, 4506.864794056183, 4518.4126063612175, 4556.498785133633, 4035.1526179502634, 4061.878143655695, 4068.5117785865887, 4083.2037752637953, 4092.6112019358116, 5681.829545113653, 5683.374828768832, 5688.77244910199, 5689.08974564076, 5690.032694441955, 5808.128780682931, 5857.062869940789, 5883.882322353615, 5962.34414214436, 5965.701714914237, 5635.549156067505, 5700.942990570041, 5703.315602127771, 5710.343310519261, 5736.5123964462655, 5173.120569503193, 5173.611979101583, 5175.7153356564995, 5182.0, 5182.041322355376, 5978.880422391615, 6084.482818960438, 6111.174991376108, 6123.173371594867, 6124.938750612526, 5860.765569377178, 5867.501262392744, 5941.1088336749835, 5944.256967879015, 6017.849553761191, 6144.664145829851, 6169.167162433194, 6171.6297022178815, 6181.412765787243, 6190.164327815687, 5874.089516125007, 5878.882422351615, 5887.61730487517, 5901.527877769854, 5914.911276330772, 5736.885262294814, 5854.619449735754, 5865.384134665798, 5884.32330282023, 5920.023196172583, 5759.481646651821, 5820.417958204146, 5827.3007239131575, 5830.737616807339, 5860.531157507475, 5794.159846822103, 5799.971000145141, 5800.4489573490455, 5802.970985145047, 5816.563757718103, 5561.026820306511, 5566.666000040011, 5729.369769325454, 5747.9137812932, 5775.422457754359, 5885.293764748258, 5908.970455147697, 5912.083627037816, 5931.317898441562, 5943.108533719977, 5750.424957504216, 5754.942450575608, 5758.222639943554, 5760.222369979999, 5767.711614419179], "value": 5470.973480128326, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.8788554403575, 596.561527277451, 619.5477301569854, 619.6777675608683, 652.4226059936955, 35227.11932201693, 38444.61555384445, 47372.763136184316, 47993.28010079843, 48291.27563086548, 49834.25248621274, 50268.49731502684, 50311.24533132006, 50380.4885853425, 50428.0, 9296.90703092969, 9448.81102377952, 9449.291303152257, 9455.858162127573, 9462.053794620551, 8457.154284571525, 8680.826383472328, 10914.727131821688, 11285.097192224603, 11298.661040168767, 11433.485493152786, 11516.539338426453, 11519.596814111508, 11530.942345288304, 11532.135089868252, 11235.101191904629, 11273.041791447737, 11285.435728213597, 11316.434178291134, 11321.377324247163, 10980.0, 11115.277506962102, 11129.666110016713, 11148.386838723867, 11150.944245278723, 46642.30036549454, 46946.53053469456, 47008.52991470076, 47112.29331560029, 47114.76442617765, 3684.299983003221, 4050.784764570617, 4249.936250956238, 4343.305071188594, 4427.867163985085, 35013.82493087544, 35075.82462087673, 35583.542238669186, 35739.64260357389, 36093.097672558135, 4787.0904528139545, 4850.53919877611, 4913.091078150518, 4931.112399768041, 4996.100701873661, 380434.0, 383413.0, 387392.0, 387706.0, 388138.0, 69008.0, 69228.0, 69450.0, 69458.3054169462, 69505.0, 40522.0, 41388.37917431241, 41480.0, 43079.0, 72909.63545182346, 19856.205751770056, 20043.799562004486, 20054.89972550157, 20057.598848022957, 20079.59840803205, 13873.37569809361, 14312.928435357757, 15155.318010689545, 15262.542123736306, 15266.313015914311, 20631.896840515703, 21174.364769056952, 21241.68137477908, 21858.234961776263, 21921.561568768535, 7318.121825380988, 7439.181690014094, 7469.962650186714, 7538.246175382527, 7667.156612772591, 1029.994850025745, 1040.2239869274485, 1075.424647813416, 1085.3596378136938, 1089.8746644135867, 34329.31341373159, 34907.82546087304, 35092.12269693278, 35151.82424087863, 35322.64677353245, 1131.6995417413796, 1154.584349634131, 1169.128998895828, 1180.6363649984282, 1261.7539579781856, 17752.91123544374, 17885.783476914567, 17986.19062142207, 19151.52121196981, 19276.42170734852, 605735.0, 609404.0, 609536.6662166892, 610935.0, 611774.0, 65553.67223163949, 66173.66913165404, 66278.33721662722, 66294.33705662882, 66317.33682663208, 1298.5130576033987, 1374.5463996881006, 1376.167418711685, 1446.8191476065492, 1460.116629439174, 30901.0, 33735.32529349448, 34899.825500872335, 34908.82545587256, 34909.65090349064, 54609.0, 55179.0, 55356.44643553674, 55373.16940245819, 55378.446215537326, 432234.0, 436401.0, 436612.0, 436657.0, 437809.0, 32019.35961280808, 33086.0, 33267.66732332743, 34523.13692157716, 34552.30895382129, 52357.73821131022, 52370.73814631055, 52470.73764631152, 52974.73512632412, 53023.73488132535, 43332.566674332855, 44594.3310850331, 44947.77526112348, 45471.31793023174, 45974.54025459837, 43224.56775432205, 44528.33207501955, 44616.33075503935, 45604.771976139906, 45804.31293530533, 44085.559144408144, 45833.77083114563, 47098.29352559783, 47173.0, 48184.0, 37967.0, 40020.39969400403, 40366.39450408305, 41322.0, 41354.0, 29742.0, 30086.849565752033, 30162.396752064396, 30190.396192075597, 30485.847570762005], "value": 22167.413999611817, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.8640462242837, 440.1175642836115, 440.79723327269454, 443.55201246740785, 448.0635471863806, 17111.657766844655, 19602.803971960293, 20434.386968390936, 21053.894730526325, 21183.788162118413, 19573.510662233417, 20547.38357849263, 20664.17343306266, 20813.791862081376, 20842.58314833706, 5917.497012753921, 5927.377625349341, 6162.568620196588, 6174.10475481055, 6181.505479561647, 6025.246844144482, 6085.817425477243, 6113.480354169901, 6121.540884433663, 6121.755129794803, 5862.091375836731, 6015.608985415955, 6080.270367555878, 6080.665563394012, 6084.939150608504, 6007.789727359544, 6029.60807547508, 6050.727717252734, 6057.3336932937345, 6057.485113765335, 5436.456354364532, 5447.972760136174, 5451.3458384994055, 5482.725863706799, 5490.423505531941, 24165.879170604036, 24318.635220471708, 24686.629700554506, 24771.0, 25273.241802745946, 2506.5738824399896, 2601.0506165249735, 2607.739226077396, 3116.7506599472213, 3220.0983724557163, 15357.692846143127, 15397.538073857802, 15411.460598878986, 15417.15205663688, 15620.297086631128, 1966.9606607867906, 2039.898005099739, 2041.2651445479617, 2046.6623007203793, 2092.424583239606, 86797.56601217017, 86829.56585217097, 87100.0, 87157.0, 88097.11902880954, 10231.488425578691, 11246.268992515461, 11349.092072634334, 11964.461599228056, 11977.880221197676, 15148.545543633709, 15777.921110394374, 15847.762283565755, 15994.520164395086, 16529.752053719203, 8743.475391476532, 8800.8679869802, 8975.551222438851, 8993.775155621162, 9047.909520904706, 8642.265407440438, 8652.295933599988, 8787.956060219658, 8921.598528066123, 8999.460032398078, 6866.896996545056, 7026.718931242693, 7034.366906978396, 7068.293170682993, 7094.50338476312, 2058.361907808584, 2116.565486536152, 2122.299641118458, 2131.9553418824976, 2137.6366017777013, 952.3999880075646, 1044.6500422358595, 1066.2429674930731, 1085.8696956365286, 1097.7036200225893, 19539.609207815764, 19568.3151089714, 20301.289454869304, 20834.10380069455, 21200.0, 1038.1746511523356, 1069.6737495063924, 1076.5855145768935, 1080.9729756756144, 1090.923635345534, 10748.247622666338, 11706.356150411639, 11845.763084738257, 11882.821757673642, 11951.641450756491, 440899.0, 479415.0, 492471.0, 505842.0, 508529.0, 78423.21576784273, 80358.19641803624, 80510.0, 81392.0, 82738.58630706929, 1052.9631462898762, 1084.3764835219781, 1108.866935967689, 1112.5382966069153, 1112.5994641928899, 18158.0, 18353.724694129865, 18657.38706714098, 18753.71869421987, 19022.334218302294, 45357.0, 45575.77212113918, 46148.0, 46290.748021699364, 46786.76606616945, 61118.388816113045, 61156.0, 61805.69097154486, 62543.374566253755, 63003.0, 8918.286537076889, 9200.125988031126, 9367.953160234156, 9375.718728438158, 9441.331295635888, 12949.287789171685, 13403.0, 13545.661358466117, 13614.591562253148, 13670.043096983514, 11867.762644746885, 11979.460924258256, 12056.0, 12086.093542984487, 12255.509779608716, 11564.884351156381, 11699.356535390636, 11807.822882656596, 11916.106292028306, 12219.389030548615, 11613.129015323715, 11650.0, 11984.700382490508, 12060.698482538006, 12267.938660306641, 8935.240504557185, 9205.401648892801, 9260.30547708938, 9367.344285899922, 9526.761830954281, 11815.940920295343, 12128.454219560317, 12152.696182595153, 12250.203736757083, 12267.815982760445], "value": 9918.404508388705, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1369.7808350663895, 1505.7515509940856, 1546.7834503169554, 1668.5911951571866, 1848.6395152945165, 8189.959050204748, 9079.500627465486, 9407.38851974623, 9432.481213533261, 9683.61265549379, 9446.905530944705, 9521.428714277148, 9522.476263805487, 9523.190528805042, 9537.0, 2431.9027238910467, 2456.5823809952262, 2461.7661322174454, 2462.642916777066, 2463.4949835283815, 2405.891734871926, 2498.4503409249937, 2523.709773376058, 2524.684414448194, 2528.329992551975, 2414.408469924871, 2426.9635955460594, 2466.1984854922134, 2470.987645061772, 2474.604063349864, 2401.939951501209, 2418.3954011497126, 2427.0291883246664, 2462.089027059981, 2464.544059349026, 2378.6432035194684, 2401.219603628828, 2401.3156250468705, 2406.7833894949363, 2422.915197968063, 9285.767855803591, 9326.813463730756, 9328.580213890391, 9331.860022099674, 9416.10546998041, 5300.787968481256, 5507.008738427082, 5508.779648814043, 5521.365043020064, 5521.723913804334, 3458.4639380896, 3495.8252087395526, 3535.4696795480627, 3541.043918142087, 3562.287542491512, 1171.8183681529376, 1185.0697202695928, 1204.7168915304896, 1206.294317824074, 1219.1161407979143, 12732.745345093046, 12772.61682149537, 12785.488580456771, 12848.935755321258, 12857.421416036208, 4544.136614043321, 4713.858584242478, 4823.879403014953, 4850.296706977469, 4857.635677324215, 10023.498825058718, 10045.497725113715, 10047.949760251153, 10048.648297309559, 10061.446620435947, 5383.111786555174, 5388.380336261302, 5389.0, 5395.703236321961, 5396.865078373072, 4522.4573051234065, 4659.510751371057, 5002.59979201663, 5029.572486338702, 5093.643444958817, 4004.2191772604647, 4052.6555242804693, 4161.625453709181, 4163.437935878678, 4194.0, 1193.1051711216587, 1208.5649166300125, 1225.0322245426191, 1226.3806777577445, 1233.6237447578396, 883.2757139145967, 892.2505095719627, 972.8151651186394, 983.8425851863667, 1029.9433531155858, 6139.6009259398, 6307.148534947815, 6319.526035547352, 6517.543771936013, 6523.956167013255, 977.9511024448749, 1018.4602160854758, 1033.4471057983942, 1044.2429238801808, 1053.9683809485728, 4507.414036175276, 4612.884677883079, 4618.907621847544, 4636.35091087248, 4637.837675681402, 11619.360935148648, 12007.519699211934, 12087.033037357167, 12124.27254364741, 12156.635300940987, 11797.233179843282, 11830.112741544419, 11938.164328497096, 11947.10396720232, 11988.940055299669, 8821.823563528693, 8845.557722113868, 8851.778705532284, 8953.462792232618, 8970.0, 6427.357264273535, 6523.64119973406, 6540.640264785482, 6609.0, 6624.138861947916, 10233.130183934449, 10558.577656893638, 10634.521446534769, 10744.0, 10938.398388088732, 10510.316829406063, 10572.41851698164, 10825.0, 10836.67489975302, 10943.562257509611, 7678.347340476123, 7841.647125879464, 8619.224269815746, 8802.647894084164, 9037.50293733851, 9008.369414140947, 9290.256779457492, 9290.396124251902, 9293.814123717622, 9293.953530232306, 8048.678052878054, 8126.471779334324, 8136.918630813616, 8163.79590510242, 8303.252707256375, 7846.686132554634, 7919.604019798871, 8016.799580010313, 8044.35645148375, 8085.8787118192095, 7221.386182174364, 7291.380232680282, 7383.630818458948, 7391.556506609621, 7413.888791668021, 5490.121580547093, 5523.751431185687, 5660.037793575019, 5706.657600543981, 5716.142578613241, 5326.334208223972, 5569.136783798518, 5599.524040456607, 5643.661380317194, 5668.829935101953], "value": 4891.598167668941, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.62314880949407, 362.4165094198341, 365.42080801928944, 366.46862050027465, 373.9663430291273, 5633.690147041911, 5682.863427314533, 5687.886242275157, 5697.145428185774, 5715.0, 5331.253624492574, 5521.199426083215, 5639.351474580424, 5641.52047075998, 5648.717564121798, 4324.329728892027, 4371.497277813044, 4385.605295523403, 4390.100029493946, 4402.207602631525, 4376.358865425465, 4388.736675799446, 4390.517043125254, 4392.3631073494325, 4401.36180253863, 4352.499462561798, 4361.193179261847, 4363.778142120212, 4366.454193225847, 4372.89067773305, 4261.531231564525, 4339.6528277738025, 4347.608715215646, 4354.673399495019, 4355.760433176174, 3898.200868821885, 3907.765534067965, 3913.4912461380122, 3917.4711413959035, 3919.7256192066566, 5380.5964552658315, 5381.300430943991, 5394.109971854639, 5404.648697834628, 5421.72589441481, 4914.6147736298435, 4977.377827771528, 4998.650094493387, 5005.749712514396, 5047.293378926953, 5794.82615521535, 5795.739191736382, 5795.971020144872, 5813.215215945836, 5825.300963884361, 4307.763073030982, 4315.741055536647, 4317.0, 4319.049809042006, 4341.80461879213, 5667.8583035424035, 5808.912866307009, 5842.707864606795, 5995.61028533144, 6039.8188054358, 4266.146770645883, 4365.519792822787, 4367.519572846984, 4373.387725718402, 4385.188740083095, 4409.2724700424715, 4427.512973572904, 4438.245498265303, 4460.219461594273, 4462.576055274743, 4675.22858728306, 4766.833160839354, 4832.4684284728655, 5005.224190250517, 5012.298278241049, 5718.714064296769, 5742.0, 5763.962486752424, 5765.54067559461, 5800.796972106041, 1517.9848201518064, 1541.4990128208153, 1545.7526795712856, 1548.2258870564717, 1552.5031989763393, 999.5252255178732, 1015.3806178231273, 1016.3393794033938, 1024.9955044056867, 1035.5554002167084, 4946.554810067111, 4969.826056088092, 4973.751312434364, 4973.806286491216, 4983.825566105242, 5194.818181363709, 5198.636095473281, 5206.875874475285, 5215.217717342353, 5239.633225674166, 4622.768861556909, 4945.208766597327, 4961.925571116436, 4968.677035992649, 4972.801087956514, 2766.5020296346447, 2778.7776977841722, 2786.749192572678, 2788.6235358226786, 2811.0, 5295.258663787073, 5319.069162896478, 5340.412554618989, 5357.963973689973, 5361.35663720356, 5312.707801070978, 5699.686517241592, 6092.3603021682975, 6139.907901381483, 6148.1392605035335, 6129.835331287041, 6135.8341915036, 6137.539684523678, 6175.475084617858, 6175.5677102602385, 5679.432056794288, 5953.0, 5972.940270597239, 5999.940000599938, 6015.87968240624, 5918.763249470146, 5942.811437712388, 5944.1083837423, 5958.0, 5958.672273025025, 5880.970595146997, 5968.194293770285, 6004.939950600438, 6085.4523092921845, 6107.27456251695, 4310.37499562568, 4325.567443255649, 4329.350597410414, 4346.217680817484, 4348.499922508826, 5768.394318596487, 5926.940730592639, 5992.700364981821, 5995.250593675791, 5997.910031349446, 5848.122781582797, 5850.063989761617, 5851.64890106595, 5853.590248682722, 5871.7651293949475, 5814.098814683731, 5815.5929084963645, 5816.796412125555, 5840.474357307863, 5843.065109582616, 5511.448855114457, 5750.108733146369, 5763.538916886723, 5794.62334948227, 5798.478136967693, 5856.765729370777, 5895.469407753323, 5899.292084949833, 5910.46805787464, 5919.911201331897, 5523.204958388459, 5741.425857414225, 5746.913796293143, 5755.482006619257, 5774.538036957118], "value": 4390.1899853217365, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [788.4638445856815, 822.6791551294996, 853.8377708235435, 873.3144481581959, 931.9673811416598, 14680.632984175401, 16093.0, 16226.107564083986, 16233.350665973347, 16233.91883040586, 14317.0, 14411.207383593897, 14418.639534011654, 16521.256543455544, 17204.139793010363, 694.4270976444441, 714.1751277274756, 821.109096630157, 837.6984285657159, 850.3367373448689, 858.6608289725585, 859.2266959736256, 860.2472836268265, 861.2033868671485, 861.3711990247124, 728.2208037399984, 749.3630414147958, 926.6571368593596, 927.281356948367, 928.0811996123856, 898.2813749000806, 914.4147745442889, 922.8661844032612, 922.9261659067258, 922.9446233226028, 673.2022553274386, 673.6968364236113, 673.8113328268092, 674.2280089297726, 675.0448115915973, 16255.918720406322, 16268.837311626969, 16288.674226515403, 16292.592685182964, 16299.185040747916, 14199.36102875373, 14750.409983600643, 14762.483313084098, 14995.625109372244, 15018.174000429972, 13700.794488082687, 13894.096883702527, 14015.579532614038, 14228.786568201485, 14485.0, 4248.808803603845, 4274.2092712848225, 4299.957000429987, 4347.956520434786, 4496.0, 88410.0, 89334.0, 90083.0, 90688.0, 91638.0, 11560.94219528897, 12132.939335303266, 12288.0, 12447.066470014784, 12487.425855908989, 22136.66794998076, 22212.555748884934, 22253.0, 22271.55456890853, 22297.219597314015, 9270.67552635668, 9449.858252126223, 9451.432914025181, 9524.714258572254, 9673.9032609673, 4885.2427873679635, 5122.410922743858, 5185.948140518546, 5199.714015729096, 5279.47205279469, 3461.2558299965626, 3570.946435803465, 3583.0863129902227, 3601.981990090033, 3602.6757591816863, 661.256086902235, 666.3336663336663, 670.8457054877406, 675.7803713793037, 690.0167261652126, 674.2044387622652, 793.8848866914265, 797.5453991225062, 802.5906787538397, 806.2542148512603, 10339.689809305582, 10590.894091059145, 10602.25784195099, 10875.293105948089, 11003.779924401628, 700.457145712067, 776.4836383804819, 791.1574173505198, 793.6586968861159, 823.6993497373415, 5263.657862239019, 5341.866453338697, 5375.16684913839, 5376.247325374451, 5534.861628459321, 131571.3421432906, 135039.0, 136423.0, 140190.0, 141578.0, 115599.42200288945, 117885.0, 118247.0, 118254.40872795582, 120838.3958080204, 59115.0, 61062.0, 62419.0, 78808.0, 98595.99999999856, 10029.398236105857, 10057.49712514386, 10365.0, 10366.06705396518, 10522.263441559324, 58358.4164158353, 59640.70179649074, 60469.395306046375, 60731.0, 61181.69409152926, 84541.0, 88002.0, 88920.0, 89104.0, 90856.0, 11985.460654270404, 12249.69375765613, 12283.69290767738, 12534.749305013665, 12910.80633790475, 14709.338079786221, 14833.33250003774, 14966.850331496546, 15240.085594864344, 15346.0, 13575.321233938066, 13630.045896787526, 13707.383167757276, 13857.37641806101, 14149.151050936976, 13717.382717777924, 13993.650158745706, 14334.426622934965, 14646.048006879519, 15193.316300766272, 12570.685732856751, 12900.806487902877, 13362.599122026355, 13382.0, 14071.155730656194, 10489.160867130458, 10687.625933092304, 10695.358278503314, 10721.571137154426, 10959.06847917936, 10990.560377585127, 11224.887751122711, 11302.943485282522, 11313.151513636674, 11417.1437142213], "value": 8047.801277547407, "unit": "score"}], "name": "dromaeo_css", "value": 7696.85154687572, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 110.0, 112.0, 109.0, 111.0, 113.0, 110.0, 111.0, 109.0, 111.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 117.0, 120.0, 165.0, 117.0, 121.0, 121.0, 115.0, 156.0, 117.0], "value": 129.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 160.0, 162.0, 160.0, 163.0, 162.0, 160.0, 162.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 76.0, 110.0, 75.0, 76.0, 76.0, 76.0, 104.0, 75.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 117.0, 83.0, 82.0, 82.0, 100.0, 81.0, 82.0, 111.0], "value": 90.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 118.0, 116.0, 117.0, 117.0, 116.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 149.0, 149.0, 151.0, 147.0, 149.0, 148.0, 147.0, 149.0], "value": 148.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 94.0, 95.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 49.0, 49.0, 49.0, 48.0, 49.0, 50.0, 49.0, 50.0], "value": 49.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 58.0, 58.0, 59.0, 58.0, 58.0, 58.0, 62.0, 59.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 73.0, 73.0, 75.0, 74.0, 79.0, 73.0, 73.0, 72.0], "value": 73.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 124.0, 120.0, 118.0, 119.0, 120.0, 127.0, 122.0, 157.0, 121.0], "value": 125.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 159.0, 152.0, 153.0, 155.0, 153.0, 152.0, 152.0, 153.0, 150.0], "value": 153.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 66.0, 67.0, 62.0, 64.0, 62.0, 61.0, 65.0, 61.0], "value": 63.3, "unit": "score"}], "name": "kraken", "value": 1458.1000000000001, "alertThreshold": 2.0}]} 04:48:43 INFO - Return code: 0 04:48:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:48:43 INFO - # TBPL SUCCESS # 04:48:43 INFO - Running post-action listener: _resource_record_post_action 04:48:43 INFO - [mozharness: 2016-11-06 12:48:43.759909Z] Finished run-tests step (success) 04:48:43 INFO - Running post-run listener: _resource_record_post_run 04:48:43 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 369119232; Read time: 12; Write time: 219852 04:48:43 INFO - TinderboxPrint: CPU usage
13.0% 04:48:43 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 04:48:43 INFO - TinderboxPrint: I/O write bytes / time
369,119,232 / 219,852 04:48:43 INFO - TinderboxPrint: CPU idle
8,265.5 (87.0%) 04:48:43 INFO - TinderboxPrint: CPU user
1,188.1 (12.5%) 04:48:43 INFO - TinderboxPrint: Swap in / out
0 / 0 04:48:43 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 108179456; Read time: 0; Write time: 86912 04:48:43 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 249397248; Read time: 12; Write time: 130440 04:48:44 INFO - Running post-run listener: _upload_blobber_files 04:48:44 INFO - Blob upload gear active. 04:48:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:48:44 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 04:48:44 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'] 04:48:44 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 04:48:44 INFO - (blobuploader) - INFO - Open directory for files ... 04:48:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:48:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:48:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:48:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:48:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:48:48 INFO - (blobuploader) - INFO - Done attempting. 04:48:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:48:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:48:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:48:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:48:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:48:48 INFO - (blobuploader) - INFO - Done attempting. 04:48:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:48:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:48:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:48:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:48:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:48:49 INFO - (blobuploader) - INFO - Done attempting. 04:48:49 INFO - (blobuploader) - INFO - Iteration through files over. 04:48:49 INFO - Return code: 0 04:48:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:48:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:48:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9a24a646e845c6c554480a131bddabc7d47e94aa6c1fcc1267aa9b470775bfd63ea699ff50ce3610be8fae4508482100568a6aef6070b25c6aeb61080cbf7903", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f235ccd0149e8034ea7f7591cfdbad692295822627a1cdd766b2deabd4f85be014ef5d2614d8e46ea5e74482b6df1b57bc854d22623859f34697c3f4e257e6d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/66a62b6b68b2e0399001076246a12f8fa3c648d4468b36d5582211afe86e95b4494d0af6124c55e94a8fd8653889b8bc60ee1a14dad4b480eb5e291eb96e5195"} 04:48:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:48:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:48:49 INFO - Contents: 04:48:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9a24a646e845c6c554480a131bddabc7d47e94aa6c1fcc1267aa9b470775bfd63ea699ff50ce3610be8fae4508482100568a6aef6070b25c6aeb61080cbf7903", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f235ccd0149e8034ea7f7591cfdbad692295822627a1cdd766b2deabd4f85be014ef5d2614d8e46ea5e74482b6df1b57bc854d22623859f34697c3f4e257e6d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/66a62b6b68b2e0399001076246a12f8fa3c648d4468b36d5582211afe86e95b4494d0af6124c55e94a8fd8653889b8bc60ee1a14dad4b480eb5e291eb96e5195"} 04:48:49 INFO - Running post-run listener: copy_logs_to_upload_dir 04:48:49 INFO - Copying logs to upload dir... 04:48:49 INFO - mkdir: /builds/slave/test/build/upload/logs 04:48:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.573737 ========= master_lag: 0.32 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-11-06 04:48:49.598138) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-06 04:48:49.602623) ========= 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=03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732 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/9a24a646e845c6c554480a131bddabc7d47e94aa6c1fcc1267aa9b470775bfd63ea699ff50ce3610be8fae4508482100568a6aef6070b25c6aeb61080cbf7903", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f235ccd0149e8034ea7f7591cfdbad692295822627a1cdd766b2deabd4f85be014ef5d2614d8e46ea5e74482b6df1b57bc854d22623859f34697c3f4e257e6d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/66a62b6b68b2e0399001076246a12f8fa3c648d4468b36d5582211afe86e95b4494d0af6124c55e94a8fd8653889b8bc60ee1a14dad4b480eb5e291eb96e5195"} build_url:https://queue.taskcluster.net/v1/task/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011292 build_url: 'https://queue.taskcluster.net/v1/task/ZAnMDzCxSp6Khc8hsnkQVQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9a24a646e845c6c554480a131bddabc7d47e94aa6c1fcc1267aa9b470775bfd63ea699ff50ce3610be8fae4508482100568a6aef6070b25c6aeb61080cbf7903", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f235ccd0149e8034ea7f7591cfdbad692295822627a1cdd766b2deabd4f85be014ef5d2614d8e46ea5e74482b6df1b57bc854d22623859f34697c3f4e257e6d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/66a62b6b68b2e0399001076246a12f8fa3c648d4468b36d5582211afe86e95b4494d0af6124c55e94a8fd8653889b8bc60ee1a14dad4b480eb5e291eb96e5195"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-06 04:48:49.628208) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 04:48:49.629716) ========= 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=03627c79f6bb3d1578c4a402000001ba-1478428251.124162-1456425732 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004669 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 04:48:49.678994) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-06 04:48:49.682478) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-06 04:48:49.682887) ========= ========= Total master_lag: 0.44 =========