builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-014 starttime: 1482177935.97 results: success (0) buildid: 20161219105824 builduid: 76554646446d465093155bcd388f8786 revision: f778def55c45214a28cc397c63e866cd151b26b0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-19 12:05:35.967331) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-19 12:05:35.968095) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-19 12:05:35.968574) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-19 12:05:35.984436) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 12:05:35.984786) ========= 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=31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-19 12:05:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 99.9M=0s 2016-12-19 12:05:36 (99.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092156 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 12:05:36.090637) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 12:05:36.091050) ========= 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=31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014885 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 12:05:36.140545) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 12:05:36.140920) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev f778def55c45214a28cc397c63e866cd151b26b0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev f778def55c45214a28cc397c63e866cd151b26b0 --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=31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-19 12:05:36,331 truncating revision to first 12 chars 2016-12-19 12:05:36,331 Setting DEBUG logging. 2016-12-19 12:05:36,331 attempt 1/10 2016-12-19 12:05:36,331 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/f778def55c45?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-19 12:05:36,751 unpacking tar archive at: graphics-f778def55c45/testing/mozharness/ program finished with exit code 0 elapsedTime=0.794375 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 12:05:36.948657) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-19 12:05:36.948989) ========= script_repo_revision: f778def55c45214a28cc397c63e866cd151b26b0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-19 12:05:36.949388) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-19 12:05:36.949802) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-19 12:05:36.959242) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-12-19 12:05:36.959594) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:05:37 INFO - MultiFileLogger online at 20161219 12:05:37 in /builds/slave/test 12:05:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 12:05:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:05:37 INFO - {'append_to_log': False, 12:05:37 INFO - 'base_work_dir': '/builds/slave/test', 12:05:37 INFO - 'blob_upload_branch': 'Graphics', 12:05:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:05:37 INFO - 'branch': 'Graphics', 12:05:37 INFO - 'buildbot_json_path': 'buildprops.json', 12:05:37 INFO - 'config_files': ('talos/linux_config.py',), 12:05:37 INFO - 'default_actions': ('clobber', 12:05:37 INFO - 'read-buildbot-config', 12:05:37 INFO - 'download-and-extract', 12:05:37 INFO - 'populate-webroot', 12:05:37 INFO - 'create-virtualenv', 12:05:37 INFO - 'install', 12:05:37 INFO - 'run-tests'), 12:05:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:05:37 INFO - 'download_minidump_stackwalk': True, 12:05:37 INFO - 'download_symbols': 'ondemand', 12:05:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:05:37 INFO - 'tooltool.py': '/tools/tooltool.py', 12:05:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:05:37 INFO - '/tools/misc-python/virtualenv.py')}, 12:05:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:05:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:05:37 INFO - 'installer_path': 'installer.exe', 12:05:37 INFO - 'log_level': 'info', 12:05:37 INFO - 'log_name': 'talos', 12:05:37 INFO - 'log_to_console': True, 12:05:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:05:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:05:37 INFO - 'opt_config_files': (), 12:05:37 INFO - 'pip_index': False, 12:05:37 INFO - 'sps_profile': False, 12:05:37 INFO - 'sps_profile_interval': 0, 12:05:37 INFO - 'suite': 'dromaeojs', 12:05:37 INFO - 'system_bits': '32', 12:05:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:05:37 INFO - 'title': 'talos-linux64-ix-014', 12:05:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:05:37 INFO - 'use_talos_json': True, 12:05:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:05:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:05:37 INFO - 'work_dir': 'build'} 12:05:37 INFO - [mozharness: 2016-12-19 20:05:37.249253Z] Running clobber step. 12:05:37 INFO - Running pre-action listener: _resource_record_pre_action 12:05:37 INFO - Running main action method: clobber 12:05:37 INFO - rmtree: /builds/slave/test/build 12:05:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:05:38 INFO - Running post-action listener: _resource_record_post_action 12:05:38 INFO - [mozharness: 2016-12-19 20:05:38.220165Z] Finished clobber step (success) 12:05:38 INFO - [mozharness: 2016-12-19 20:05:38.220265Z] Running read-buildbot-config step. 12:05:38 INFO - Running pre-action listener: _resource_record_pre_action 12:05:38 INFO - Running main action method: read_buildbot_config 12:05:38 INFO - Using buildbot properties: 12:05:38 INFO - { 12:05:38 INFO - "project": "", 12:05:38 INFO - "product": "firefox", 12:05:38 INFO - "script_repo_revision": "default", 12:05:38 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 12:05:38 INFO - "repository": "", 12:05:38 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 12:05:38 INFO - "buildid": "20161219105824", 12:05:38 INFO - "pgo_build": "False", 12:05:38 INFO - "basedir": "/builds/slave/test", 12:05:38 INFO - "buildnumber": 0, 12:05:38 INFO - "slavename": "talos-linux64-ix-014", 12:05:38 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:05:38 INFO - "platform": "ubuntu64_hw", 12:05:38 INFO - "scheduler": "tests-graphics-linux64-talos", 12:05:38 INFO - "branch": "graphics", 12:05:38 INFO - "repo_path": "projects/graphics", 12:05:38 INFO - "revision": "f778def55c45214a28cc397c63e866cd151b26b0", 12:05:38 INFO - "stage_platform": "linux64", 12:05:38 INFO - "builduid": "76554646446d465093155bcd388f8786", 12:05:38 INFO - "slavebuilddir": "test" 12:05:38 INFO - } 12:05:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 12:05:38 INFO - Running post-action listener: _resource_record_post_action 12:05:38 INFO - [mozharness: 2016-12-19 20:05:38.222714Z] Finished read-buildbot-config step (success) 12:05:38 INFO - [mozharness: 2016-12-19 20:05:38.222804Z] Running download-and-extract step. 12:05:38 INFO - Running pre-action listener: _resource_record_pre_action 12:05:38 INFO - Running main action method: download_and_extract 12:05:38 INFO - mkdir: /builds/slave/test/build/tests 12:05:38 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')]} 12:05:38 INFO - trying https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 12:05:38 INFO - Downloading https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 12:05:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 12:05:38 INFO - Downloaded 1361 bytes. 12:05:38 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 12:05:38 INFO - Using the following test package requirements: 12:05:38 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 12:05:38 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:05:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:05:38 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:05:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:05:38 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:05:38 INFO - u'jsshell-linux-x86_64.zip'], 12:05:38 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:05:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:05:38 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 12:05:38 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:05:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:05:38 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:05:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:05:38 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:05:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:05:38 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:05:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:05:38 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:05:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 12:05:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:05:38 INFO - Fetch https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 12:05:39 INFO - Expected file size: 20258275 12:05:39 INFO - Obtained file size: 20258275 12:05:43 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 12:05:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 12:05:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:05:43 INFO - Fetch https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 12:05:44 INFO - Expected file size: 20258275 12:05:44 INFO - Obtained file size: 20258275 12:05:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 12:05:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:05:48 INFO - Fetch https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 12:05:50 INFO - Expected file size: 14409734 12:05:50 INFO - Obtained file size: 14409734 12:05:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:50 INFO - trying https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 12:05:50 INFO - Downloading https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 12:05:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 12:05:51 INFO - Downloaded 55643786 bytes. 12:05:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 12:05:51 INFO - mkdir: /builds/slave/test/properties 12:05:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:05:51 INFO - Writing to file /builds/slave/test/properties/build_url 12:05:51 INFO - Contents: 12:05:51 INFO - build_url:https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 12:05:52 INFO - Running post-action listener: _resource_record_post_action 12:05:52 INFO - Running post-action listener: set_extra_try_arguments 12:05:52 INFO - [mozharness: 2016-12-19 20:05:52.631007Z] Finished download-and-extract step (success) 12:05:52 INFO - [mozharness: 2016-12-19 20:05:52.631235Z] Running populate-webroot step. 12:05:52 INFO - Running pre-action listener: _resource_record_pre_action 12:05:52 INFO - Running main action method: populate_webroot 12:05:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:05:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:05:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:05:52 INFO - '--tppagecycles', 12:05:52 INFO - '7'], 12:05:52 INFO - 'tests': ['tsvgm']}}, 12:05:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:05:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:05:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:05:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:05:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:05:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:05:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:05:52 INFO - 'talos_options': ['--disable-e10s'], 12:05:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:05:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:05:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:05:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:05:52 INFO - 'talos_options': ['--disable-e10s'], 12:05:52 INFO - 'tests': ['damp', 'tps']}, 12:05:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:05:52 INFO - 'tests': ['damp', 'tps']}, 12:05:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:05:52 INFO - 'tests': ['dromaeo_dom']}, 12:05:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:05:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:05:52 INFO - 'tests': ['basic_compositor_video']}, 12:05:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:05:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:05:52 INFO - 'tests': ['a11yr', 12:05:52 INFO - 'ts_paint', 12:05:52 INFO - 'tpaint', 12:05:52 INFO - 'sessionrestore', 12:05:52 INFO - 'sessionrestore_no_auto_restore', 12:05:52 INFO - 'tabpaint']}, 12:05:52 INFO - 'other-e10s': {'tests': ['a11yr', 12:05:52 INFO - 'ts_paint', 12:05:52 INFO - 'tpaint', 12:05:52 INFO - 'sessionrestore', 12:05:52 INFO - 'sessionrestore_no_auto_restore', 12:05:52 INFO - 'tabpaint']}, 12:05:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:05:52 INFO - 'ts_paint', 12:05:52 INFO - 'tpaint', 12:05:52 INFO - 'sessionrestore', 12:05:52 INFO - 'sessionrestore_no_auto_restore', 12:05:52 INFO - 'tabpaint']}, 12:05:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:05:52 INFO - 'ts_paint', 12:05:52 INFO - 'tpaint', 12:05:52 INFO - 'sessionrestore', 12:05:52 INFO - 'sessionrestore_no_auto_restore', 12:05:52 INFO - 'tabpaint']}, 12:05:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:05:52 INFO - 'tests': ['a11yr', 12:05:52 INFO - 'ts_paint', 12:05:52 INFO - 'tpaint', 12:05:52 INFO - 'sessionrestore', 12:05:52 INFO - 'sessionrestore_no_auto_restore', 12:05:52 INFO - 'tabpaint']}, 12:05:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:05:52 INFO - 'tests': ['a11yr', 12:05:52 INFO - 'ts_paint', 12:05:52 INFO - 'tpaint', 12:05:52 INFO - 'sessionrestore', 12:05:52 INFO - 'sessionrestore_no_auto_restore', 12:05:52 INFO - 'tabpaint']}, 12:05:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:05:52 INFO - 'tests': ['tsvgx', 12:05:52 INFO - 'tsvgr_opacity', 12:05:52 INFO - 'tart', 12:05:52 INFO - 'tscrollx', 12:05:52 INFO - 'cart', 12:05:52 INFO - 'tsvg_static']}, 12:05:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:05:52 INFO - 'tsvgr_opacity', 12:05:52 INFO - 'tart', 12:05:52 INFO - 'tscrollx', 12:05:52 INFO - 'cart', 12:05:52 INFO - 'tsvg_static']}, 12:05:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:05:52 INFO - 'talos_options': ['--disable-e10s'], 12:05:52 INFO - 'tests': ['tp5o']}, 12:05:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:05:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:05:52 INFO - 'talos_options': ['--disable-e10s', 12:05:52 INFO - '--xperf_path', 12:05:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:05:52 INFO - 'tests': ['tp5n']}, 12:05:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:05:52 INFO - 'talos_options': ['--xperf_path', 12:05:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:05:52 INFO - 'tests': ['tp5n']}}, 12:05:52 INFO - 'talos.zip': {'path': '', 12:05:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:05:52 INFO - Running post-action listener: _resource_record_post_action 12:05:52 INFO - [mozharness: 2016-12-19 20:05:52.651209Z] Finished populate-webroot step (success) 12:05:52 INFO - [mozharness: 2016-12-19 20:05:52.651298Z] Running create-virtualenv step. 12:05:52 INFO - Running pre-action listener: _resource_record_pre_action 12:05:52 INFO - Running main action method: create_virtualenv 12:05:52 INFO - Creating virtualenv /builds/slave/test/build/venv 12:05:52 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 12:05:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:05:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:05:52 INFO - Using real prefix '/usr' 12:05:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:05:53 INFO - Installing distribute.............................................................................................................................................................................................done. 12:05:55 INFO - Installing pip.................done. 12:05:55 INFO - Return code: 0 12:05:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:05:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:05:55 INFO - Reading from file tmpfile_stdout 12:05:55 INFO - Output received: 12:05:55 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:05:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:05:55 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')]} 12:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:05:55 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')]} 12:05:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:05:55 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 0x15001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16cca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1871c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x186f3f0>, '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 0x186fcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18701a0>, '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': '31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342', '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 12:05:55 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 12:05:55 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 12:05:55 INFO - Using env: {'DISPLAY': ':0', 12:05:55 INFO - 'HOME': '/home/cltbld', 12:05:55 INFO - 'LANG': 'en_US.UTF-8', 12:05:55 INFO - 'LANGUAGE': 'en_US:en', 12:05:55 INFO - 'LOGNAME': 'cltbld', 12:05:55 INFO - 'MAIL': '/var/mail/cltbld', 12:05:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:55 INFO - 'MOZ_NO_REMOTE': '1', 12:05:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:05:55 INFO - 'NO_EM_RESTART': '1', 12:05:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:05:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:55 INFO - 'PWD': '/builds/slave/test', 12:05:55 INFO - 'SHELL': '/bin/bash', 12:05:55 INFO - 'SHLVL': '1', 12:05:55 INFO - 'TERM': 'linux', 12:05:55 INFO - 'TMOUT': '86400', 12:05:55 INFO - 'USER': 'cltbld', 12:05:55 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342', 12:05:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:55 INFO - '_': '/tools/buildbot/bin/python'} 12:05:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:55 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 12:05:55 INFO - Cleaning up... 12:05:55 INFO - Return code: 0 12:05:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:05:55 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')]} 12:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:05:55 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')]} 12:05:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:05:55 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 0x15001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16cca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1871c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x186f3f0>, '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 0x186fcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18701a0>, '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': '31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342', '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 12:05:55 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 12:05:55 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 12:05:55 INFO - Using env: {'DISPLAY': ':0', 12:05:55 INFO - 'HOME': '/home/cltbld', 12:05:55 INFO - 'LANG': 'en_US.UTF-8', 12:05:55 INFO - 'LANGUAGE': 'en_US:en', 12:05:55 INFO - 'LOGNAME': 'cltbld', 12:05:55 INFO - 'MAIL': '/var/mail/cltbld', 12:05:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:55 INFO - 'MOZ_NO_REMOTE': '1', 12:05:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:05:55 INFO - 'NO_EM_RESTART': '1', 12:05:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:05:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:55 INFO - 'PWD': '/builds/slave/test', 12:05:55 INFO - 'SHELL': '/bin/bash', 12:05:55 INFO - 'SHLVL': '1', 12:05:55 INFO - 'TERM': 'linux', 12:05:55 INFO - 'TMOUT': '86400', 12:05:55 INFO - 'USER': 'cltbld', 12:05:55 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342', 12:05:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:55 INFO - '_': '/tools/buildbot/bin/python'} 12:05:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:55 INFO - Downloading/unpacking psutil>=3.1.1 12:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:05:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:05:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:05:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:05:57 INFO - Installing collected packages: psutil 12:05:57 INFO - Running setup.py install for psutil 12:05:58 INFO - building 'psutil._psutil_linux' extension 12:05:58 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 12:05:58 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 12:05:58 INFO - building 'psutil._psutil_posix' extension 12:05:58 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 12:05:58 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 12:05:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:05:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:05:58 INFO - Successfully installed psutil 12:05:58 INFO - Cleaning up... 12:05:58 INFO - Return code: 0 12:05:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:05:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:05:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:05:58 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 0x15001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16cca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1871c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x186f3f0>, '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 0x186fcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18701a0>, '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': '31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342', '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 12:05:58 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 12:05:58 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 12:05:58 INFO - Using env: {'DISPLAY': ':0', 12:05:58 INFO - 'HOME': '/home/cltbld', 12:05:58 INFO - 'LANG': 'en_US.UTF-8', 12:05:58 INFO - 'LANGUAGE': 'en_US:en', 12:05:58 INFO - 'LOGNAME': 'cltbld', 12:05:58 INFO - 'MAIL': '/var/mail/cltbld', 12:05:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:58 INFO - 'MOZ_NO_REMOTE': '1', 12:05:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:05:58 INFO - 'NO_EM_RESTART': '1', 12:05:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:05:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:58 INFO - 'PWD': '/builds/slave/test', 12:05:58 INFO - 'SHELL': '/bin/bash', 12:05:58 INFO - 'SHLVL': '1', 12:05:58 INFO - 'TERM': 'linux', 12:05:58 INFO - 'TMOUT': '86400', 12:05:58 INFO - 'USER': 'cltbld', 12:05:58 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342', 12:05:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:58 INFO - '_': '/tools/buildbot/bin/python'} 12:05:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:05:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:05:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:05:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:06:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:06:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:06:00 INFO - Installing collected packages: mozsystemmonitor 12:06:00 INFO - Running setup.py install for mozsystemmonitor 12:06:00 INFO - Successfully installed mozsystemmonitor 12:06:00 INFO - Cleaning up... 12:06:00 INFO - Return code: 0 12:06:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:06:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16cca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1871c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x186f3f0>, '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 0x186fcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18701a0>, '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': '31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342', '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 12:06:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:06:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:06:00 INFO - Using env: {'DISPLAY': ':0', 12:06:00 INFO - 'HOME': '/home/cltbld', 12:06:00 INFO - 'LANG': 'en_US.UTF-8', 12:06:00 INFO - 'LANGUAGE': 'en_US:en', 12:06:00 INFO - 'LOGNAME': 'cltbld', 12:06:00 INFO - 'MAIL': '/var/mail/cltbld', 12:06:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:00 INFO - 'MOZ_NO_REMOTE': '1', 12:06:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:00 INFO - 'NO_EM_RESTART': '1', 12:06:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:00 INFO - 'PWD': '/builds/slave/test', 12:06:00 INFO - 'SHELL': '/bin/bash', 12:06:00 INFO - 'SHLVL': '1', 12:06:00 INFO - 'TERM': 'linux', 12:06:00 INFO - 'TMOUT': '86400', 12:06:00 INFO - 'USER': 'cltbld', 12:06:00 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342', 12:06:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:00 INFO - '_': '/tools/buildbot/bin/python'} 12:06:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:01 INFO - Downloading/unpacking jsonschema==2.5.1 12:06:01 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 12:06:01 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 12:06:01 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 12:06:01 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 12:06:01 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 12:06:01 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 12:06:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:06:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:06:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:03 INFO - Downloading functools32-3.2.3-2.tar.gz 12:06:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:06:03 INFO - warning: no files found matching '*.txt' 12:06:03 INFO - no previously-included directories found matching 'build' 12:06:03 INFO - no previously-included directories found matching 'dist' 12:06:03 INFO - no previously-included directories found matching '.git*' 12:06:03 INFO - Installing collected packages: jsonschema, functools32 12:06:03 INFO - Running setup.py install for functools32 12:06:03 INFO - warning: no files found matching '*.txt' 12:06:03 INFO - no previously-included directories found matching 'build' 12:06:03 INFO - no previously-included directories found matching 'dist' 12:06:03 INFO - no previously-included directories found matching '.git*' 12:06:03 INFO - Successfully installed jsonschema functools32 12:06:03 INFO - Cleaning up... 12:06:03 INFO - Return code: 0 12:06:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:06:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16cca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1871c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x186f3f0>, '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 0x186fcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18701a0>, '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': '31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342', '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 12:06:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:06:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:06:03 INFO - Using env: {'DISPLAY': ':0', 12:06:03 INFO - 'HOME': '/home/cltbld', 12:06:03 INFO - 'LANG': 'en_US.UTF-8', 12:06:03 INFO - 'LANGUAGE': 'en_US:en', 12:06:03 INFO - 'LOGNAME': 'cltbld', 12:06:03 INFO - 'MAIL': '/var/mail/cltbld', 12:06:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:03 INFO - 'MOZ_NO_REMOTE': '1', 12:06:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:03 INFO - 'NO_EM_RESTART': '1', 12:06:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:03 INFO - 'PWD': '/builds/slave/test', 12:06:03 INFO - 'SHELL': '/bin/bash', 12:06:03 INFO - 'SHLVL': '1', 12:06:03 INFO - 'TERM': 'linux', 12:06:03 INFO - 'TMOUT': '86400', 12:06:03 INFO - 'USER': 'cltbld', 12:06:03 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342', 12:06:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:03 INFO - '_': '/tools/buildbot/bin/python'} 12:06:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:06:03 INFO - Cleaning up... 12:06:03 INFO - Return code: 0 12:06:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:06:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16cca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1871c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x186f3f0>, '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 0x186fcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18701a0>, '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': '31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342', '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 12:06:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:06:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:06:03 INFO - Using env: {'DISPLAY': ':0', 12:06:03 INFO - 'HOME': '/home/cltbld', 12:06:03 INFO - 'LANG': 'en_US.UTF-8', 12:06:03 INFO - 'LANGUAGE': 'en_US:en', 12:06:03 INFO - 'LOGNAME': 'cltbld', 12:06:03 INFO - 'MAIL': '/var/mail/cltbld', 12:06:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:03 INFO - 'MOZ_NO_REMOTE': '1', 12:06:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:03 INFO - 'NO_EM_RESTART': '1', 12:06:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:03 INFO - 'PWD': '/builds/slave/test', 12:06:03 INFO - 'SHELL': '/bin/bash', 12:06:03 INFO - 'SHLVL': '1', 12:06:03 INFO - 'TERM': 'linux', 12:06:03 INFO - 'TMOUT': '86400', 12:06:03 INFO - 'USER': 'cltbld', 12:06:03 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342', 12:06:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:03 INFO - '_': '/tools/buildbot/bin/python'} 12:06:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:04 INFO - Downloading/unpacking blobuploader==1.2.4 12:06:04 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 12:06:04 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 12:06:04 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 12:06:04 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 12:06:04 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 12:06:04 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 12:06:06 INFO - Downloading blobuploader-1.2.4.tar.gz 12:06:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:06:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:06:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:06:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:06:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:06 INFO - Downloading docopt-0.6.1.tar.gz 12:06:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:06:06 INFO - Installing collected packages: blobuploader, requests, docopt 12:06:06 INFO - Running setup.py install for blobuploader 12:06:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:06:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:06:06 INFO - Running setup.py install for requests 12:06:07 INFO - Running setup.py install for docopt 12:06:07 INFO - Successfully installed blobuploader requests docopt 12:06:07 INFO - Cleaning up... 12:06:07 INFO - Return code: 0 12:06:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:06:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16cca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1871c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x186f3f0>, '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 0x186fcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18701a0>, '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': '31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342', '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 12:06:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:06:07 INFO - Using env: {'DISPLAY': ':0', 12:06:07 INFO - 'HOME': '/home/cltbld', 12:06:07 INFO - 'LANG': 'en_US.UTF-8', 12:06:07 INFO - 'LANGUAGE': 'en_US:en', 12:06:07 INFO - 'LOGNAME': 'cltbld', 12:06:07 INFO - 'MAIL': '/var/mail/cltbld', 12:06:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:07 INFO - 'MOZ_NO_REMOTE': '1', 12:06:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:07 INFO - 'NO_EM_RESTART': '1', 12:06:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:07 INFO - 'PWD': '/builds/slave/test', 12:06:07 INFO - 'SHELL': '/bin/bash', 12:06:07 INFO - 'SHLVL': '1', 12:06:07 INFO - 'TERM': 'linux', 12:06:07 INFO - 'TMOUT': '86400', 12:06:07 INFO - 'USER': 'cltbld', 12:06:07 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342', 12:06:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:07 INFO - '_': '/tools/buildbot/bin/python'} 12:06:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:06:07 INFO - Running setup.py (path:/tmp/pip-nz6EPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:06:07 INFO - Running setup.py (path:/tmp/pip-UoPg_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:06:07 INFO - Running setup.py (path:/tmp/pip-pZ4W4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:06:07 INFO - Running setup.py (path:/tmp/pip-Mu36Xz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:06:07 INFO - Running setup.py (path:/tmp/pip-zcXqp2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:06:08 INFO - Running setup.py (path:/tmp/pip-otpEgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:06:08 INFO - Running setup.py (path:/tmp/pip-c1Z4Th-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:06:08 INFO - Running setup.py (path:/tmp/pip-bAcTKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:06:08 INFO - Running setup.py (path:/tmp/pip-bBSSoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:06:08 INFO - Running setup.py (path:/tmp/pip-VhuQf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:06:08 INFO - Running setup.py (path:/tmp/pip-HAFANH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:06:08 INFO - Running setup.py (path:/tmp/pip-vXfjPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:06:08 INFO - Running setup.py (path:/tmp/pip-fJuyUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:06:08 INFO - Running setup.py (path:/tmp/pip-eYCihv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:06:08 INFO - Running setup.py (path:/tmp/pip-Knhssb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:06:08 INFO - Running setup.py (path:/tmp/pip-jFY0gl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:06:08 INFO - Running setup.py (path:/tmp/pip-5kTNK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:06:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:06:08 INFO - Running setup.py install for manifestparser 12:06:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:06:08 INFO - Running setup.py install for mozcrash 12:06:09 INFO - Running setup.py install for mozdebug 12:06:09 INFO - Running setup.py install for mozdevice 12:06:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:06:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:06:09 INFO - Running setup.py install for mozfile 12:06:09 INFO - Running setup.py install for mozhttpd 12:06:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:06:09 INFO - Running setup.py install for mozinfo 12:06:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:06:09 INFO - Running setup.py install for mozInstall 12:06:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:06:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:06:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:06:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:06:09 INFO - Running setup.py install for mozleak 12:06:09 INFO - Running setup.py install for mozlog 12:06:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:06:09 INFO - Running setup.py install for moznetwork 12:06:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:06:10 INFO - Running setup.py install for mozprocess 12:06:10 INFO - Running setup.py install for mozprofile 12:06:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:06:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:06:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:06:10 INFO - Running setup.py install for mozrunner 12:06:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:06:10 INFO - Running setup.py install for mozscreenshot 12:06:10 INFO - Running setup.py install for moztest 12:06:10 INFO - Running setup.py install for mozversion 12:06:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:06:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:06:10 INFO - Cleaning up... 12:06:10 INFO - Return code: 0 12:06:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:06: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')]} 12:06:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06: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')]} 12:06:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16cca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1871c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x186f3f0>, '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 0x186fcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18701a0>, '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': '31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342', '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 12:06:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:06:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:06:10 INFO - Using env: {'DISPLAY': ':0', 12:06:10 INFO - 'HOME': '/home/cltbld', 12:06:10 INFO - 'LANG': 'en_US.UTF-8', 12:06:10 INFO - 'LANGUAGE': 'en_US:en', 12:06:10 INFO - 'LOGNAME': 'cltbld', 12:06:10 INFO - 'MAIL': '/var/mail/cltbld', 12:06:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:10 INFO - 'MOZ_NO_REMOTE': '1', 12:06:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:10 INFO - 'NO_EM_RESTART': '1', 12:06:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:10 INFO - 'PWD': '/builds/slave/test', 12:06:10 INFO - 'SHELL': '/bin/bash', 12:06:10 INFO - 'SHLVL': '1', 12:06:10 INFO - 'TERM': 'linux', 12:06:10 INFO - 'TMOUT': '86400', 12:06:10 INFO - 'USER': 'cltbld', 12:06:10 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342', 12:06:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:10 INFO - '_': '/tools/buildbot/bin/python'} 12:06:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:06:10 INFO - Running setup.py (path:/tmp/pip-Xb5whI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:06:10 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:06:10 INFO - Running setup.py (path:/tmp/pip-V0XmmZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:06:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:06:11 INFO - Running setup.py (path:/tmp/pip-XJVmG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:06:11 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)) 12:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:06:11 INFO - Running setup.py (path:/tmp/pip-SxbT_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:06:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:06:11 INFO - Running setup.py (path:/tmp/pip-kvPAjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:06:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:06:11 INFO - Running setup.py (path:/tmp/pip-M1Cjcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:06:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:06:11 INFO - Running setup.py (path:/tmp/pip-0hF20C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:06:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:06:11 INFO - Running setup.py (path:/tmp/pip-qiWKCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:06:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:06:11 INFO - Running setup.py (path:/tmp/pip-W4G6DT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:06:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:06:11 INFO - Running setup.py (path:/tmp/pip-So2Jsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:06:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:06:11 INFO - Running setup.py (path:/tmp/pip-1Z2tCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:06:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:06:11 INFO - Running setup.py (path:/tmp/pip-4oCl4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:06:11 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)) 12:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:06:11 INFO - Running setup.py (path:/tmp/pip-v35uvy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:06:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:06:11 INFO - Running setup.py (path:/tmp/pip-feIpWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:06:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:06:11 INFO - Running setup.py (path:/tmp/pip-42Mcwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:06:12 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)) 12:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:06:12 INFO - Running setup.py (path:/tmp/pip-pukt34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:06:12 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)) 12:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:06:12 INFO - Running setup.py (path:/tmp/pip-_WYIix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:06:12 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)) 12:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:06:12 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)) 12:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:06:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:06:12 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 12:06:12 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 12:06:12 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 12:06:12 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 12:06:12 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 12:06:12 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 12:06:14 INFO - Downloading blessings-1.6.tar.gz 12:06:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:06:14 INFO - Installing collected packages: blessings 12:06:14 INFO - Running setup.py install for blessings 12:06:14 INFO - Successfully installed blessings 12:06:14 INFO - Cleaning up... 12:06:14 INFO - Return code: 0 12:06:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:06:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:06:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:06:14 INFO - Reading from file tmpfile_stdout 12:06:14 INFO - Current package versions: 12:06:14 INFO - argparse == 1.2.1 12:06:14 INFO - blessings == 1.6 12:06:14 INFO - blobuploader == 1.2.4 12:06:14 INFO - docopt == 0.6.1 12:06:14 INFO - functools32 == 3.2.3-2 12:06:14 INFO - jsonschema == 2.5.1 12:06:14 INFO - manifestparser == 1.1 12:06:14 INFO - mozInstall == 1.12 12:06:14 INFO - mozcrash == 1.0 12:06:14 INFO - mozdebug == 0.1 12:06:14 INFO - mozdevice == 0.49 12:06:14 INFO - mozfile == 1.2 12:06:14 INFO - mozhttpd == 0.7 12:06:14 INFO - mozinfo == 0.9 12:06:14 INFO - mozleak == 0.1 12:06:14 INFO - mozlog == 3.4 12:06:14 INFO - moznetwork == 0.27 12:06:14 INFO - mozprocess == 0.23 12:06:14 INFO - mozprofile == 0.28 12:06:14 INFO - mozrunner == 6.13 12:06:14 INFO - mozscreenshot == 0.1 12:06:14 INFO - mozsystemmonitor == 0.3 12:06:14 INFO - moztest == 0.7 12:06:14 INFO - mozversion == 1.4 12:06:14 INFO - psutil == 3.1.1 12:06:14 INFO - requests == 1.2.3 12:06:14 INFO - wsgiref == 0.1.2 12:06:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:06:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16cca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1871c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x186f3f0>, '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 0x186fcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18701a0>, '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': '31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342', '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 12:06:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:06:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:06:14 INFO - Using env: {'DISPLAY': ':0', 12:06:14 INFO - 'HOME': '/home/cltbld', 12:06:14 INFO - 'LANG': 'en_US.UTF-8', 12:06:14 INFO - 'LANGUAGE': 'en_US:en', 12:06:14 INFO - 'LOGNAME': 'cltbld', 12:06:14 INFO - 'MAIL': '/var/mail/cltbld', 12:06:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:14 INFO - 'MOZ_NO_REMOTE': '1', 12:06:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:14 INFO - 'NO_EM_RESTART': '1', 12:06:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:14 INFO - 'PWD': '/builds/slave/test', 12:06:14 INFO - 'SHELL': '/bin/bash', 12:06:14 INFO - 'SHLVL': '1', 12:06:14 INFO - 'TERM': 'linux', 12:06:14 INFO - 'TMOUT': '86400', 12:06:14 INFO - 'USER': 'cltbld', 12:06:14 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342', 12:06:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:14 INFO - '_': '/tools/buildbot/bin/python'} 12:06:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:06:14 INFO - Cleaning up... 12:06:14 INFO - Return code: 0 12:06:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:06:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16cca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1871c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x186f3f0>, '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 0x186fcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18701a0>, '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': '31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342', '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 12:06:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:06:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:06:14 INFO - Using env: {'DISPLAY': ':0', 12:06:14 INFO - 'HOME': '/home/cltbld', 12:06:14 INFO - 'LANG': 'en_US.UTF-8', 12:06:14 INFO - 'LANGUAGE': 'en_US:en', 12:06:14 INFO - 'LOGNAME': 'cltbld', 12:06:14 INFO - 'MAIL': '/var/mail/cltbld', 12:06:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:14 INFO - 'MOZ_NO_REMOTE': '1', 12:06:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:14 INFO - 'NO_EM_RESTART': '1', 12:06:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:14 INFO - 'PWD': '/builds/slave/test', 12:06:14 INFO - 'SHELL': '/bin/bash', 12:06:14 INFO - 'SHLVL': '1', 12:06:14 INFO - 'TERM': 'linux', 12:06:14 INFO - 'TMOUT': '86400', 12:06:14 INFO - 'USER': 'cltbld', 12:06:14 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342', 12:06:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:14 INFO - '_': '/tools/buildbot/bin/python'} 12:06:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:06:15 INFO - Cleaning up... 12:06:15 INFO - Return code: 0 12:06:15 INFO - Running post-action listener: _resource_record_post_action 12:06:15 INFO - Running post-action listener: _start_resource_monitoring 12:06:15 INFO - Starting resource monitoring. 12:06:15 INFO - [mozharness: 2016-12-19 20:06:15.085137Z] Finished create-virtualenv step (success) 12:06:15 INFO - [mozharness: 2016-12-19 20:06:15.085659Z] Running install step. 12:06:15 INFO - Running pre-action listener: _resource_record_pre_action 12:06:15 INFO - Running main action method: install 12:06:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:06:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:06:15 INFO - Reading from file tmpfile_stdout 12:06:15 INFO - Detecting whether we're running mozinstall >=1.0... 12:06:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:06:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:06:15 INFO - Reading from file tmpfile_stdout 12:06:15 INFO - Output received: 12:06:15 INFO - Usage: mozinstall [options] installer 12:06:15 INFO - Options: 12:06:15 INFO - -h, --help show this help message and exit 12:06:15 INFO - -d DEST, --destination=DEST 12:06:15 INFO - Directory to install application into. [default: 12:06:15 INFO - "/builds/slave/test"] 12:06:15 INFO - --app=APP Application being installed. [default: firefox] 12:06:15 INFO - mkdir: /builds/slave/test/build/application 12:06:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:06:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 12:06:31 INFO - Reading from file tmpfile_stdout 12:06:31 INFO - Output received: 12:06:31 INFO - /builds/slave/test/build/application/firefox/firefox 12:06:31 INFO - Running post-action listener: _resource_record_post_action 12:06:31 INFO - [mozharness: 2016-12-19 20:06:31.454019Z] Finished install step (success) 12:06:31 INFO - [mozharness: 2016-12-19 20:06:31.454332Z] Running run-tests step. 12:06:31 INFO - Running pre-action listener: _resource_record_pre_action 12:06:31 INFO - Running main action method: run_tests 12:06:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:06:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:06:31 INFO - Python 2.7.3 12:06:31 INFO - Return code: 0 12:06:31 INFO - grabbing minidump binary from tooltool 12:06:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:31 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x186f3f0>, '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 0x186fcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18701a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:06:31 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:06:31 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 12:06:31 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:06:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:06:31 INFO - Return code: 0 12:06:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:06:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:06:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:06:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:06:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:06:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 12:06:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --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 12:06:31 INFO - Using env: {'DISPLAY': ':0', 12:06:31 INFO - 'HOME': '/home/cltbld', 12:06:31 INFO - 'LANG': 'en_US.UTF-8', 12:06:31 INFO - 'LANGUAGE': 'en_US:en', 12:06:31 INFO - 'LOGNAME': 'cltbld', 12:06:31 INFO - 'MAIL': '/var/mail/cltbld', 12:06:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:06:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:06:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:31 INFO - 'MOZ_NO_REMOTE': '1', 12:06:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:06:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:31 INFO - 'NO_EM_RESTART': '1', 12:06:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:31 INFO - 'PWD': '/builds/slave/test', 12:06:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:06:31 INFO - 'SHELL': '/bin/bash', 12:06:31 INFO - 'SHLVL': '1', 12:06:31 INFO - 'TERM': 'linux', 12:06:31 INFO - 'TMOUT': '86400', 12:06:31 INFO - 'USER': 'cltbld', 12:06:31 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342', 12:06:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:31 INFO - '_': '/tools/buildbot/bin/python'} 12:06:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 12:06:31 INFO - mozversion application_buildid: 20161219105824 12:06:31 INFO - mozversion application_changeset: f778def55c45214a28cc397c63e866cd151b26b0 12:06:31 INFO - mozversion application_display_name: Nightly 12:06:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:06:31 INFO - mozversion application_name: Firefox 12:06:31 INFO - mozversion application_remotingname: firefox 12:06:31 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 12:06:31 INFO - mozversion application_vendor: Mozilla 12:06:31 INFO - mozversion application_version: 53.0a1 12:06:31 INFO - mozversion platform_buildid: 20161219105824 12:06:31 INFO - mozversion platform_changeset: f778def55c45214a28cc397c63e866cd151b26b0 12:06:31 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 12:06:31 INFO - mozversion platform_version: 53.0a1 12:06:31 INFO - using testdate: 1482177991 12:06:31 INFO - actual date: 1482177991 12:06:31 INFO - starting webserver on 'localhost:60920' 12:06:31 INFO - SUITE-START | Running 2 tests 12:06:31 INFO - TEST-START | dromaeo_css 12:06:31 INFO - operating with platform_type : linux_ 12:06:31 INFO - Initialising browser for dromaeo_css test... 12:06:31 INFO - TEST-INFO | started process 1012 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuQnrLI/profile http://localhost:60920/getInfo.html) 12:06:35 INFO - PROCESS | 1012 | __metrics Screen width/height:1600/1200 12:06:35 INFO - PROCESS | 1012 | colorDepth:24 12:06:35 INFO - PROCESS | 1012 | Browser inner width/height: 1024/697 12:06:35 INFO - PROCESS | 1012 | __metrics 12:06:36 INFO - PROCESS | 1012 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:06:36 INFO - PROCESS | 1012 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:06:36 INFO - PROCESS | 1012 | console.error: PushService: 12:06:36 INFO - PROCESS | 1012 | stateChangeProcessEnqueue: Error transitioning state 12:06:36 INFO - PROCESS | 1012 | UnknownError 12:06:37 INFO - TEST-INFO | 1012: exit 0 12:06:37 INFO - Browser initialized. 12:06:37 INFO - Running cycle 1/1 for dromaeo_css test... 12:06:37 INFO - TEST-INFO | started process 1109 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuQnrLI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:06:49 INFO - PROCESS | 1109 | RSS: Main: 155398144 12:06:49 INFO - PROCESS | 1109 | 12:07:40 INFO - PROCESS | 1109 | 1482178060569 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] 12:09:54 INFO - PROCESS | 1109 | Cycle 1(1): loaded http://localhost:60920/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60920/tests/dromaeo/cssquery-ext.html) 12:09:56 INFO - PROCESS | 1109 | RSS: Main: 213487616 12:09:56 INFO - PROCESS | 1109 | 12:12:58 INFO - PROCESS | 1109 | Cycle 1(1): loaded http://localhost:60920/tests/dromaeo/cssquery-ext.html (next: http://localhost:60920/tests/dromaeo/cssquery-jquery.html) 12:12:59 INFO - PROCESS | 1109 | RSS: Main: 247750656 12:12:59 INFO - PROCESS | 1109 | 12:16:02 INFO - PROCESS | 1109 | Cycle 1(1): loaded http://localhost:60920/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60920/tests/dromaeo/cssquery-mootools.html) 12:16:02 INFO - PROCESS | 1109 | RSS: Main: 229888000 12:16:02 INFO - PROCESS | 1109 | 12:19:04 INFO - PROCESS | 1109 | Cycle 1(1): loaded http://localhost:60920/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60920/tests/dromaeo/cssquery-prototype.html) 12:19:05 INFO - PROCESS | 1109 | RSS: Main: 189939712 12:19:05 INFO - PROCESS | 1109 | 12:22:08 INFO - PROCESS | 1109 | Cycle 1(1): loaded http://localhost:60920/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60920/tests/dromaeo/cssquery-yui.html) 12:22:09 INFO - PROCESS | 1109 | RSS: Main: 188043264 12:22:09 INFO - PROCESS | 1109 | 12:25:12 INFO - PROCESS | 1109 | Cycle 1(1): loaded http://localhost:60920/tests/dromaeo/cssquery-yui.html (next: http://localhost:60920/tests/dromaeo/cssquery-dojo.html) 12:25:13 INFO - PROCESS | 1109 | RSS: Main: 285798400 12:25:13 INFO - PROCESS | 1109 | 12:25:13 INFO - PROCESS | 1109 | __start_tp_report 12:25:13 INFO - PROCESS | 1109 | _x_x_mozilla_page_load 12:25:13 INFO - PROCESS | 1109 | _x_x_mozilla_page_load_details 12:25:13 INFO - PROCESS | 1109 | |i|pagename|runs| 12:25:13 INFO - PROCESS | 1109 | |0;dojo.html;2558.462722828206;2634.5126151661957;2662.467506498701;2681.0750291149557;2683.6511253537037;4621.514740952202;4638.072385522901;4763.190257656198;4854.36893203883;4880.243562247849;4744.596709279706;4786.186348320785;4797.472278049412;4821.8794530136765;4907.877303067425;4309.784510774464;4354.934675979863;4359.80380882861;4421.027373977736;4471.4187155669715;4325.913481730364;4364.25807612705;4398.054418300065;4415.492218394877;4468.486124095722;4412.97793511032;4415.51429342772;4422.380866678667;4449.243628583133;4463.531329210435;4288.807003684841;4305.418768466248;4305.763183024932;4338.761368124752;4440.800163992628;4382.780860956939;4386.254336762758;4386.429764130675;4425.601695847389;4443.42235509385;4813.157697402976;4846.079244943449;4846.321514987904;4866.318715379814;4870.366852309213;4911.336969509142;4946.233333833262;5226.608004399685;5233.712145831978;5264.684118952875;5041.873953151164;5076.593872490192;5099.949000509984;5143.5113664201845;5220.060389129993;4804.039192161582;4816.807327706888;4817.590504807061;4824.975875120672;4865.099956508057;5279.287296215039;5285.471452854684;5294.152935530296;5296.682199068068;5298.81454149103;4922.827701030518;4968.354113965162;4970.304157417964;4992.326035985168;5036.194208926554;5115.155999260155;5137.023965446628;5143.048536020848;5182;5195.246689230041;5055.064813009605;5096.821611243588;5112.233165025202;5155.510226528474;5156.123459011977;4899.191633380524;4927.975360123177;4935.062338155739;5057.190849464068;5096.490350964874;4246.363045543131;4277.486701595828;4278.507971583245;4304.332828411601;4348.195583817004;3758.530183727034;3769.754965927206;3773.8867833965023;3802.7908465034134;3803.5435747710453;4290.549492303326;4419.580139886705;4421.292593185075;4426.933595996063;4457.487388950312;4999.975000125049;5065.341505604248;5085.771140298695;5097.8215762448135;5167.509086636764;4385.386045953569;4387.802548885308;4388.144311859221;4393.494748103945;4437.090396468716;4562.84030058953;4572.314152877095;4574.4510658721165;4577.1074640444795;4619.630429565626;4655.255159174651;4665.32352808834;4668.5798278155125;4709.222978208557;4720.905581888412;5120.103981803095;5127.2052831811125;5130.281760553451;5141.434442211428;5193.584513238856;4774.904501909873;4779.593734532465;4781.450133234723;4785.090832741768;4832.492588278321;4824.204006339055;4848.151573474698;5022.4977502249485;5033.094043072282;5080.7205603692155;5085.923711144262;5143.61422893292;5147.510986456281;5195.272661827423;5207.453217412269;5024.824131155392;5033.169527028;5034.823781167642;5039.0929632666475;5081.187010078517;4753.524647535219;4788.497207793132;5025.6482046256415;5030.62270329734;5038.949610503848;4920.926186107283;4946.233333833262;4953.133201689762;4977.328060711758;5029.044481548494;5030.949690503048;5053.898922021465;5057.165567681292;5065.265536497261;5119.846404607868;4783.617310615073;4814.927776083432;4819.3012013258585;4841.176999910094;4878.121938051043 12:25:13 INFO - PROCESS | 1109 | |1;ext.html;479.95680388765015;525.9079661059313;554.8917960997604;559.4433538629065;565.3329071695398;35812.641873581255;38025.42961855575;38053.429198562044;38145.80927095367;38187.427188592126;38100;38615;38635.61364386355;38681.41977870327;38699.41950870739;7272.527285726411;7487.43844211685;7705.9596954411;7708.03649543807;7709.922900771005;7225.349718525332;9628.855567166498;9874.1113299803;9878;10193.796124077515;9527.571259293349;9559.713208603753;9567.904320956806;9575.281853860954;9575.664851730191;9719;9740.561674724619;9768.853467197998;9832.508374581243;9832.606695732162;9708.805823883482;9783.168430683329;9784.657536986242;9785.657501987467;9785.706428807147;36023.63976360229;36410.45384319211;36451.4532282016;36453.63546364529;36541.269174616624;2781.735735105162;2832.3344014156655;2939.2945693033516;2952.8818847246084;3274.5088236764436;26241.21276361693;26535.336616584547;26662.20013399582;27305.180844574694;27461.588076178872;3269.4605390110605;3346.0631023313513;3373.2747459296124;3436.226848958964;3469.60099588548;246858;247000;247591;248312;248312;43530.7823460887;44119.55880441154;44123;44169.11661766747;44188.77905610516;46942;46956;46973.5302646976;47000.294995575096;47013.76493117581;12603.243805371707;12877.291748953898;12890.871091288967;12896.226226426257;12903.290319032543;8921.955390223136;9156.221721153643;9409.435433873989;9433.811323773487;9901.603935842486;11864.288142711464;12011.219270747548;12151.392430378623;12178;12255;6154.599951003171;6363.172787537591;6458.867449287092;6503.894337962558;6535.673216339164;757.9166291707908;877.7761670774039;962.2542529539663;990.5195979949718;1008.3344992305043;26316;26950.191494255203;27104.1868743938;27129.86435067812;27322.316942076606;880.8678698195322;894.1550235027967;947.8056998315329;985.6944347252374;998.1116806042628;13364.55691146212;14194.290285485684;14500.854991450162;14511.637209069646;14552.85447145536;342637;344071;344101;345117;346957;46895.76552117217;47159.29261061087;47412.288815667795;47412.76293618579;47540.5245947543;985.3545927417553;1062.490004797686;1078.0890147825075;1079.3577821196375;1146.8853114688463;25701.742982570682;27443.86278068597;27751.861240694474;29644.258893527833;30430.695693042788;43755.124897501235;44165.11669766651;44384.55615443804;44413.33379999367;44434.11178273402;242041;243980;244599;245095.39236861365;245275;21287;21916.23293184731;23182.76817231806;23359.53280934406;23755.1685691;33469.66530334666;35061.64938350686;36061.81969090138;36079.27841443209;36132.63867361293;29151.854240728662;29152;29722.702772971992;29737.553936690536;30018.099457016324;25665.27380690574;26287.868560657073;26501.469970600865;26764.330891727863;27048;30551.388972220877;30572.541411879283;30618.540721889636;30825.691743083178;30974.22564435907;25555.744442555337;25565.87217063977;28272.575911360935;28397.432051358443;28438;21488.355349339545;21935.341939741196;22173.778262217173;22239.22162724297;22252.88873555622 12:25:13 INFO - PROCESS | 1109 | |2;jquery.html;340.76444162675404;359.8668492657717;363.3786225554302;366.1267876115464;379.33616171699526;12523.24860508369;15450.536483905487;15653.217339133054;15717;16608.501744947673;15328;15465.30406131724;15738;15816.76274855878;16044.432890737484;3664.578573464046;4416.227160246952;4548.5224051474615;4552;4578.2903649934315;3413.76103672743;3575.6424357564165;4440.866773996785;4523.00493891345;5104.489551044903;5332;5433.673979561239;5433.891322173554;5434.429384914567;5438.510534051935;5248.422673505911;5386.272853164812;5421.918671219935;5436.700981446019;5442.183672449125;5242.9213561796605;5247.449017853147;5247.711375874325;5247.868803279948;5250.133727934886;12021.759564808743;17009;19292.517687057796;19410.22359105634;19496.220151193935;1663.9001659900323;1668.5661727950699;1742.128935532234;1855.22080726094;2253.6799062532436;8360.540170290693;8397.580120993925;8630.439021463584;8677.739667809912;8782.25350845179;1660.3109709470555;1692.1285537947967;1738.3568079810425;1756.1745979389666;1763.8588912886937;64478.17799134281;66544.3345566543;66593;67809;67822;9723.416595004323;9877.209823214125;9879;10046.146077583488;10153.340032897839;13700.45198192061;13709;13716.177029378268;13740.381682824296;13751.449942002208;6734.158230221223;6909.585424874524;6931.584104953719;7104.822379440556;7106.289371062852;6537.509686773511;6546.509011824037;6698.33016698336;6717.966410167918;6749.966250168816;4277.743335399824;4301.569843015736;4306.911148093172;4360.236958532244;4404.2512772828695;1663.3928616055296;1780.8219178082347;1828.7988321284647;1896.7344571759966;1899.5725961658516;680.210955291859;753.6081237756335;754.7773406844993;755.935745461631;818.4761752478419;15645.765313520305;15744.763828542582;15825.683486330208;15997.200139992954;16749.162541872858;830.464350493928;846.3609974469236;846.6105591428012;866.536403024379;883.5847151838625;8833.116688331116;8899.955500222457;9324.813503729887;9336.39313444624;9415.576299066559;333340;339588;340129;341661;347317;70923.64538177276;71568;71735.6413217941;72464.27535724576;72636.27363726294;868.7377664268924;870.1994165367877;896.7444278380631;903.074348792493;904.8778414913902;14135.293235338399;14204.78692819588;14317.713645727235;14355.354009069408;14931.402743890123;34722;35010.29979400449;35322.47016294809;35826.28347433089;36009;47322.29016564685;47371.52628473671;47391;47466.76266618645;47776.28335575038;7371.189169191277;7412;7468.551886886804;7525.623718814147;7855.7250496232355;8464.95767521183;10941.835872461761;11559.19085664029;11682.88317116852;11935.104867135173;5326.467353264643;5516.200150978006;9662.502312141629;9677.032296770267;10154.492275386348;9483.383580067273;9739.513024348897;9976.401415915068;10034.247431442816;10132.493375331056;9741.951290243504;10212.795744085226;10233.130183934449;10412.895871041495;10524.842127367943;7259.782206533812;7328.670209840463;7340.449466289942;7423.888641670272;7431.29402706742;8520.659173632985;9759.853602196115;10142.391456512632;10177.134943529587;10448.582056717647 12:25:13 INFO - PROCESS | 1109 | |3;mootools.html;1350.6960933789896;1365.3719289127005;1462.4881291547954;1596.106180538899;1671.0558534428046;6658.800235992922;7113.715451381951;7121.394681452083;7122.537035092715;7128.394086502647;6319.873602527959;6514.7719829805965;6731.899021514681;6954.687039083241;6999.335063169004;2124.33083578673;2165.8592191507582;2168.2844661261825;2168.382011126829;2169.9132034718673;2085.718428012214;2127.4362293992103;2173;2189.770074142216;2194.36363454598;2076.9896150519303;2081.261152290938;2103.9894800526054;2108.219958615307;2111.2082968886666;2077.3352527191305;2095.6646936490088;2098.339023207692;2099.916003359879;2101.957960840775;2059.258666879922;2080.032784755081;2080.178329559815;2086.9269575564936;2090.3833369156137;7190.209077001526;7234.168070671887;7304.160021597478;7308.598027108507;7316.048913641246;4267.295896177127;4394.956050439519;4402.515723270438;4407.647388208936;4408.382826404306;2737.6441062661925;2866.2977570495364;2905.0558568465335;2907.8109922854946;2913.737763601286;860.8493513635151;896.9955150224207;900.9549522523847;909.522500687138;1679.8404151605578;9070;9480.857787133198;9495.14543691071;9506.95246523763;9513.048695130501;3725.2921944830396;3887.8056097195026;3925.548561915359;3946.8026598669953;3949.605039496085;7490.4382171337365;7499.437542184358;7509.098908131059;7510.699572017167;7521.435892307989;4392.648588112943;4392.802323895432;4394.055278115221;4395.362672412482;4399.62603178727;3569.196930690574;3697.131174174067;3969.166475040274;4091.959080409217;4097.549269580344;3141.1204862638497;3168.287135394564;3261.3314270574474;3309.7848639838335;3310.7351411886993;987.9993014146411;1005.7636455432968;1010.4594042187395;1010.5048526222246;1011.7723512209841;824.2817185384253;836.6653338664582;838.0975186327125;860.1957170046027;879.8812160358398;4820.861836172371;4913.901721965541;4956.479569645207;5096.719680417538;5128.102582048126;398.2413502278165;407.893947573633;418.2158452900811;423.6547214020625;425.06485731391064;3388.15296175956;3591.075034567813;3781.829817658212;3785.3186426442953;3801.695864330902;8505.10696376884;8703.825923481621;8795.428297160664;8802.471851688919;8815.118488151134;8779.078196789245;8782.692605758768;8785.604647790737;8791.120887911158;8797.47215167092;6565.409113179837;6713.731450741916;6764.72941082361;6822.897656535156;6850.862982740317;4920.6801557898625;4958;5068.442471328225;5102.617303702312;5200.323957885451;7618.542887426772;7715.498492597963;7769.028871391076;7793.688252470064;7994;7985.281324680806;8002.879956800769;8025;8044.195580442143;8055.07366652819;6161.9691901542;6197.256329240339;6229.96885015572;6256.906146407717;6261.812145635638;6877.243503214742;6948.965255173692;6954.826129346807;6957.965210173917;6961.651917404009;6228.906566401598;6326.936730632819;6337.081123237197;6343.143675603919;6501.9674901625185;6006.549508786946;6092.421219984094;6180.165677633642;6219.657918814509;6348;5656.943430565806;5756.309242890712;5758.021136406737;5764.740586673526;5766.452187042224;4430.889227769203;4457.955420445754;4514.774261286988;4618.053299073749;4650.48844627084;4351.260285751493;4372.278574035248;4520.2541580640145;4587.541245875385;4655.18534256497 12:25:13 INFO - PROCESS | 1109 | |4;prototype.html;349.6713089695686;351.371045827968;357.37459445969546;357.49771570942835;359.3567514149672;4826.155422801009;4835.5406236407525;4882.145624515713;4899.828506002291;4901.862807803009;4573.3825933498965;4800.183968725316;4808.062427826575;4824.493428190042;4826.107170173512;3728.384816505287;3739.8130093495215;3767.3595488766973;3778.4332350147424;3793.468914351993;3784.3944968804994;3788.886333410002;3795.3168429682783;3799.7150213733944;3808.428735689641;3781.243751249747;3785.599328248552;3788.015116069814;3793.468914351993;3796.4495148203496;3629.6370362963758;3711.5546134463766;3745.4569087482164;3803.353429916893;3803.5356387790625;3417.7778444401033;3425.06483836634;3429.4855771634207;3434.4848272759286;3448.2241495663275;4679.415073115861;4699.318598803188;4700.55344742249;4702.976485117587;4726.338312636233;4032.959670403288;4202.789860506962;4388.868333949987;4397.890052748675;4400.713953593006;5013.874653133665;5019.3976722793495;5026.84919452417;5028.673136246169;5041.386756238006;3533;3535.1515636247113;3541.3802584547857;3544.6278140795102;3549.396602577568;5059.544640982329;5245.632805703603;5323.228131920889;5323.760430780624;5327.813526526553;3596.28074385124;3609.296187243462;3611.855525778992;3624.1697942539377;3629.3305079663123;3666.5966743658178;3670.8164591770305;3675.852965881335;3679.374506333876;3709.5177626908867;4216;4220.894477638084;4227.852025179165;4238.385434112037;4251.787410629456;4947.738326726733;5008.622628777115;5044.9495505045215;5075.670081444694;5094.821681241213;1223.473906220334;1227.5028613411582;1230.8767376275853;1235.832138629005;1240.9565665201674;801.7394346837301;822.0710597025443;846.3102571404286;857.4683696108452;857.8627419612832;4210.705250632426;4210.726302790309;4232.492100947906;4235.576442355739;4240.300350442142;5083.262926875656;5144.794208231704;5172.586193104543;5173.81891633791;5198.870028249324;4340.52254252032;4456.175607512556;4460.754658493749;4464.799084041226;4464.977675111603;2669.636258314538;2693.4074503609177;2708.512467755823;2718.904838330649;2724.9727502725113;4757.857264282077;4761.381020467386;4761.976190119027;4773.355596994361;4794.664373493892;5028.823991160292;5151.728652305738;5196.986587615379;5200.323957885526;5203.973980130075;4871.8294859679745;4885.511448855157;4901.411830580353;4902.14212512816;4910.8281210157475;5129.204973229155;5136.486351364983;5136.845894623168;5190.714510701948;5232.555232805102;4933.235348520985;5070.315507406601;5100.48995100487;5112.284280200849;5165.070287348313;4878.682885612424;4888.535589119028;4894.608431325414;4895.33912921751;4898.632602554751;3642.1805093853673;3657.311875771359;3676.724245681638;3677.7679477375377;3691.2986532559803;4998.475160108136;5257.763400646903;5267.578593712417;5272.789088436573;5279;5000.874978125576;5041.117804384291;5056.595472362275;5066.695998240118;5073.949260507496;5060.772265247999;5071.17437722427;5080.212567052113;5093.745312734422;5110.71891045996;4839.95160048395;5009.7244651544515;5025.321581586439;5030.974845125751;5037.722925239001;4856.63575231852;4907.852764417074;4963.602911766978;5074;5096.566791822588;4958.355413796184;4983.377077865267;4983.9003219934675;4991.47589503097;4999.10016197088 12:25:13 INFO - PROCESS | 1109 | |5;yui.html;610.2250142319255;617.62633606668;625.7278084033446;644.3119028146256;736.7274108579822;12489;14400.135991840483;14930.850691493082;14975;15004.249787510635;14551.490697825579;14615.63460913475;14689.265536723147;14763.335649895753;14777.556673299792;585.7188549496244;600.807741522713;758.116793935065;782.3975538835093;784.6037750935769;756.440234226671;778.4745296924582;785.4541093939714;790.154534647927;792.5601291283335;831.2145022953312;831.5675848558745;832.6128350317101;833.0503226321998;833.1876420490004;609.7713357490941;628.7296462521114;817.456391499655;817.5503473089807;818.2226884459787;468.95779379855634;469.19064613541707;469.2257774671785;583.8423625621059;602.2351613450936;13597;13644.522441714493;13699.109557878706;13700.314984250748;13703.177809331371;12026;12078.939605301917;12117.454714537867;12367.443465044005;12493.564784501463;10328.173746100294;10417.5832966682;10481.63314284004;11761.529538818437;11940.820887686692;3236.9028929131928;3252.235724604716;3291.588551431071;3301.8018918864946;3379.425497665403;65360;65946.67026664836;66392.66803665951;66672.3332766671;66853;8560.61477233526;8625.611847466878;8808.163224493663;8955.328350373618;8971.372003959661;17027;17614;17894;18002.099895005198;18161.45515634533;6546.8363290918105;6912;6935.861282774316;7072.186698529632;7084.397826184832;3582.964170358263;3652.3243200008083;3653.415453527423;3667.7615954963376;3694.242680250542;2853.671827739795;2909.985450072736;2937.55936609506;2940.1914473519955;2945.6759756426777;503.58957449678394;504.1253425307089;516.7906997665951;520.8593679706458;522.9215617657305;503.5543543963619;545.2094463028625;548.6022633590615;580.6545105662209;606.1665210335789;8144.75565733029;8343.87357706714;8458.70166225743;8491;8522.70170544028;454.2981094209454;547.8712502561895;562.1174755633698;599.1192946368831;611.48024179448;3790.5451345838123;4065.288574499451;4083.953474452879;4092.7544347339253;4155.085881106152;64093.67953160204;64628.67685661541;66334.33665663282;67484.98772518417;68511;56896.146557801665;57411.425885741446;59472.10791838126;59931.70034149801;59938.70030649819;52535;52575.737121314145;52637.7368113157;53069.73465132649;53218.73390633099;7450;7848.332891704269;7907.130215676386;8023.83952320962;8095.109537950703;37062.814685926394;37316;37392;37408.251834962604;37685.2462950734;42138;47534.76232618953;48853;49135.75432122959;50665;9272.582733777132;9417.952910235404;9616.855747163938;9952.601895924083;9981.950090249502;11187.216894817284;11278.718032049264;11658;11817.645470635895;12096.516139354679;10685.145188385066;10894.67315980522;10994.785160509946;11070.889291107307;11478.196526243082;10616.89383106159;10803.675889723321;11040;11231.55073797039;11378.829317560409;10161.237907157141;10483.47582620881;10722.249442538947;10910.181736369645;11071.391073491035;9075.183233509017;9091.772705682146;9116.1339672731;9232.353735238468;9383;8905.910940890508;9260.907390926004;9284.95357523208;9287.86068208991;9504.47725375111 12:25:13 INFO - PROCESS | 1109 | __end_tp_report 12:25:13 INFO - PROCESS | 1109 | __start_cc_report 12:25:13 INFO - PROCESS | 1109 | _x_x_mozilla_cycle_collect,1701 12:25:13 INFO - PROCESS | 1109 | __end_cc_report 12:25:13 INFO - PROCESS | 1109 | __startTimestamp1482179113015__endTimestamp 12:25:13 INFO - PROCESS | 1109 | 12:25:13 INFO - PROCESS | 1109 | ------- Summary: start ------- 12:25:13 INFO - PROCESS | 1109 | Number of tests: 6 12:25:13 INFO - PROCESS | 1109 | 12:25:13 INFO - PROCESS | 1109 | [#0] dojo.html Cycles:165 Average:4717.85 Median:4832.49 stddev:503.49 (10.4%) stddev-sans-first:475.68 12:25:13 INFO - PROCESS | 1109 | Values: 2558.5 2634.5 2662.5 2681.1 2683.7 4621.5 4638.1 4763.2 4854.4 4880.2 4744.6 4786.2 4797.5 4821.9 4907.9 4309.8 4354.9 4359.8 4421.0 4471.4 4325.9 4364.3 4398.1 4415.5 4468.5 4413.0 4415.5 4422.4 4449.2 4463.5 4288.8 4305.4 4305.8 4338.8 4440.8 4382.8 4386.3 4386.4 4425.6 4443.4 4813.2 4846.1 4846.3 4866.3 4870.4 4911.3 4946.2 5226.6 5233.7 5264.7 5041.9 5076.6 5099.9 5143.5 5220.1 4804.0 4816.8 4817.6 4825.0 4865.1 5279.3 5285.5 5294.2 5296.7 5298.8 4922.8 4968.4 4970.3 4992.3 5036.2 5115.2 5137.0 5143.0 5182.0 5195.2 5055.1 5096.8 5112.2 5155.5 5156.1 4899.2 4928.0 4935.1 5057.2 5096.5 4246.4 4277.5 4278.5 4304.3 4348.2 3758.5 3769.8 3773.9 3802.8 3803.5 4290.5 4419.6 4421.3 4426.9 4457.5 5000.0 5065.3 5085.8 5097.8 5167.5 4385.4 4387.8 4388.1 4393.5 4437.1 4562.8 4572.3 4574.5 4577.1 4619.6 4655.3 4665.3 4668.6 4709.2 4720.9 5120.1 5127.2 5130.3 5141.4 5193.6 4774.9 4779.6 4781.5 4785.1 4832.5 4824.2 4848.2 5022.5 5033.1 5080.7 5085.9 5143.6 5147.5 5195.3 5207.5 5024.8 5033.2 5034.8 5039.1 5081.2 4753.5 4788.5 5025.6 5030.6 5038.9 4920.9 4946.2 4953.1 4977.3 5029.0 5030.9 5053.9 5057.2 5065.3 5119.8 4783.6 4814.9 4819.3 4841.2 4878.1 12:25:13 INFO - PROCESS | 1109 | 12:25:13 INFO - PROCESS | 1109 | [#1] ext.html Cycles:180 Average:42174.62 Median:175213.30 stddev:74193.51 (42.3%) stddev-sans-first:74335.60 12:25:13 INFO - PROCESS | 1109 | Values: 480.0 525.9 554.9 559.4 565.3 35812.6 38025.4 38053.4 38145.8 38187.4 38100.0 38615.0 38635.6 38681.4 38699.4 7272.5 7487.4 7706.0 7708.0 7709.9 7225.3 9628.9 9874.1 9878.0 10193.8 9527.6 9559.7 9567.9 9575.3 9575.7 9719.0 9740.6 9768.9 9832.5 9832.6 9708.8 9783.2 9784.7 9785.7 9785.7 36023.6 36410.5 36451.5 36453.6 36541.3 2781.7 2832.3 2939.3 2952.9 3274.5 26241.2 26535.3 26662.2 27305.2 27461.6 3269.5 3346.1 3373.3 3436.2 3469.6 246858.0 247000.0 247591.0 248312.0 248312.0 43530.8 44119.6 44123.0 44169.1 44188.8 46942.0 46956.0 46973.5 47000.3 47013.8 12603.2 12877.3 12890.9 12896.2 12903.3 8922.0 9156.2 9409.4 9433.8 9901.6 11864.3 12011.2 12151.4 12178.0 12255.0 6154.6 6363.2 6458.9 6503.9 6535.7 757.9 877.8 962.3 990.5 1008.3 26316.0 26950.2 27104.2 27129.9 27322.3 880.9 894.2 947.8 985.7 998.1 13364.6 14194.3 14500.9 14511.6 14552.9 342637.0 344071.0 344101.0 345117.0 346957.0 46895.8 47159.3 47412.3 47412.8 47540.5 985.4 1062.5 1078.1 1079.4 1146.9 25701.7 27443.9 27751.9 29644.3 30430.7 43755.1 44165.1 44384.6 44413.3 44434.1 242041.0 243980.0 244599.0 245095.4 245275.0 21287.0 21916.2 23182.8 23359.5 23755.2 33469.7 35061.6 36061.8 36079.3 36132.6 29151.9 29152.0 29722.7 29737.6 30018.1 25665.3 26287.9 26501.5 26764.3 27048.0 30551.4 30572.5 30618.5 30825.7 30974.2 25555.7 25565.9 28272.6 28397.4 28438.0 21488.4 21935.3 22173.8 22239.2 22252.9 12:25:13 INFO - PROCESS | 1109 | 12:25:13 INFO - PROCESS | 1109 | [#2] jquery.html Cycles:180 Average:22122.47 Median:47346.91 stddev:56414.58 (119.2%) stddev-sans-first:56549.13 12:25:13 INFO - PROCESS | 1109 | Values: 340.8 359.9 363.4 366.1 379.3 12523.2 15450.5 15653.2 15717.0 16608.5 15328.0 15465.3 15738.0 15816.8 16044.4 3664.6 4416.2 4548.5 4552.0 4578.3 3413.8 3575.6 4440.9 4523.0 5104.5 5332.0 5433.7 5433.9 5434.4 5438.5 5248.4 5386.3 5421.9 5436.7 5442.2 5242.9 5247.4 5247.7 5247.9 5250.1 12021.8 17009.0 19292.5 19410.2 19496.2 1663.9 1668.6 1742.1 1855.2 2253.7 8360.5 8397.6 8630.4 8677.7 8782.3 1660.3 1692.1 1738.4 1756.2 1763.9 64478.2 66544.3 66593.0 67809.0 67822.0 9723.4 9877.2 9879.0 10046.1 10153.3 13700.5 13709.0 13716.2 13740.4 13751.4 6734.2 6909.6 6931.6 7104.8 7106.3 6537.5 6546.5 6698.3 6718.0 6750.0 4277.7 4301.6 4306.9 4360.2 4404.3 1663.4 1780.8 1828.8 1896.7 1899.6 680.2 753.6 754.8 755.9 818.5 15645.8 15744.8 15825.7 15997.2 16749.2 830.5 846.4 846.6 866.5 883.6 8833.1 8900.0 9324.8 9336.4 9415.6 333340.0 339588.0 340129.0 341661.0 347317.0 70923.6 71568.0 71735.6 72464.3 72636.3 868.7 870.2 896.7 903.1 904.9 14135.3 14204.8 14317.7 14355.4 14931.4 34722.0 35010.3 35322.5 35826.3 36009.0 47322.3 47371.5 47391.0 47466.8 47776.3 7371.2 7412.0 7468.6 7525.6 7855.7 8465.0 10941.8 11559.2 11682.9 11935.1 5326.5 5516.2 9662.5 9677.0 10154.5 9483.4 9739.5 9976.4 10034.2 10132.5 9742.0 10212.8 10233.1 10412.9 10524.8 7259.8 7328.7 7340.4 7423.9 7431.3 8520.7 9759.9 10142.4 10177.1 10448.6 12:25:13 INFO - PROCESS | 1109 | 12:25:13 INFO - PROCESS | 1109 | [#3] mootools.html Cycles:180 Average:4726.95 Median:5082.58 stddev:2532.81 (49.8%) stddev-sans-first:2527.21 12:25:13 INFO - PROCESS | 1109 | Values: 1350.7 1365.4 1462.5 1596.1 1671.1 6658.8 7113.7 7121.4 7122.5 7128.4 6319.9 6514.8 6731.9 6954.7 6999.3 2124.3 2165.9 2168.3 2168.4 2169.9 2085.7 2127.4 2173.0 2189.8 2194.4 2077.0 2081.3 2104.0 2108.2 2111.2 2077.3 2095.7 2098.3 2099.9 2102.0 2059.3 2080.0 2080.2 2086.9 2090.4 7190.2 7234.2 7304.2 7308.6 7316.0 4267.3 4395.0 4402.5 4407.6 4408.4 2737.6 2866.3 2905.1 2907.8 2913.7 860.8 897.0 901.0 909.5 1679.8 9070.0 9480.9 9495.1 9507.0 9513.0 3725.3 3887.8 3925.5 3946.8 3949.6 7490.4 7499.4 7509.1 7510.7 7521.4 4392.6 4392.8 4394.1 4395.4 4399.6 3569.2 3697.1 3969.2 4092.0 4097.5 3141.1 3168.3 3261.3 3309.8 3310.7 988.0 1005.8 1010.5 1010.5 1011.8 824.3 836.7 838.1 860.2 879.9 4820.9 4913.9 4956.5 5096.7 5128.1 398.2 407.9 418.2 423.7 425.1 3388.2 3591.1 3781.8 3785.3 3801.7 8505.1 8703.8 8795.4 8802.5 8815.1 8779.1 8782.7 8785.6 8791.1 8797.5 6565.4 6713.7 6764.7 6822.9 6850.9 4920.7 4958.0 5068.4 5102.6 5200.3 7618.5 7715.5 7769.0 7793.7 7994.0 7985.3 8002.9 8025.0 8044.2 8055.1 6162.0 6197.3 6230.0 6256.9 6261.8 6877.2 6949.0 6954.8 6958.0 6961.7 6228.9 6326.9 6337.1 6343.1 6502.0 6006.5 6092.4 6180.2 6219.7 6348.0 5656.9 5756.3 5758.0 5764.7 5766.5 4430.9 4458.0 4514.8 4618.1 4650.5 4351.3 4372.3 4520.3 4587.5 4655.2 12:25:13 INFO - PROCESS | 1109 | 12:25:13 INFO - PROCESS | 1109 | [#4] prototype.html Cycles:180 Average:4177.37 Median:4797.42 stddev:1212.63 (25.3%) stddev-sans-first:1181.51 12:25:13 INFO - PROCESS | 1109 | Values: 349.7 351.4 357.4 357.5 359.4 4826.2 4835.5 4882.1 4899.8 4901.9 4573.4 4800.2 4808.1 4824.5 4826.1 3728.4 3739.8 3767.4 3778.4 3793.5 3784.4 3788.9 3795.3 3799.7 3808.4 3781.2 3785.6 3788.0 3793.5 3796.4 3629.6 3711.6 3745.5 3803.4 3803.5 3417.8 3425.1 3429.5 3434.5 3448.2 4679.4 4699.3 4700.6 4703.0 4726.3 4033.0 4202.8 4388.9 4397.9 4400.7 5013.9 5019.4 5026.8 5028.7 5041.4 3533.0 3535.2 3541.4 3544.6 3549.4 5059.5 5245.6 5323.2 5323.8 5327.8 3596.3 3609.3 3611.9 3624.2 3629.3 3666.6 3670.8 3675.9 3679.4 3709.5 4216.0 4220.9 4227.9 4238.4 4251.8 4947.7 5008.6 5044.9 5075.7 5094.8 1223.5 1227.5 1230.9 1235.8 1241.0 801.7 822.1 846.3 857.5 857.9 4210.7 4210.7 4232.5 4235.6 4240.3 5083.3 5144.8 5172.6 5173.8 5198.9 4340.5 4456.2 4460.8 4464.8 4465.0 2669.6 2693.4 2708.5 2718.9 2725.0 4757.9 4761.4 4762.0 4773.4 4794.7 5028.8 5151.7 5197.0 5200.3 5204.0 4871.8 4885.5 4901.4 4902.1 4910.8 5129.2 5136.5 5136.8 5190.7 5232.6 4933.2 5070.3 5100.5 5112.3 5165.1 4878.7 4888.5 4894.6 4895.3 4898.6 3642.2 3657.3 3676.7 3677.8 3691.3 4998.5 5257.8 5267.6 5272.8 5279.0 5000.9 5041.1 5056.6 5066.7 5073.9 5060.8 5071.2 5080.2 5093.7 5110.7 4840.0 5009.7 5025.3 5031.0 5037.7 4856.6 4907.9 4963.6 5074.0 5096.6 4958.4 4983.4 4983.9 4991.5 4999.1 12:25:13 INFO - PROCESS | 1109 | 12:25:13 INFO - PROCESS | 1109 | [#5] yui.html Cycles:180 Average:14910.23 Median:52555.37 stddev:19018.41 (36.2%) stddev-sans-first:19041.44 12:25:13 INFO - PROCESS | 1109 | Values: 610.2 617.6 625.7 644.3 736.7 12489.0 14400.1 14930.9 14975.0 15004.2 14551.5 14615.6 14689.3 14763.3 14777.6 585.7 600.8 758.1 782.4 784.6 756.4 778.5 785.5 790.2 792.6 831.2 831.6 832.6 833.1 833.2 609.8 628.7 817.5 817.6 818.2 469.0 469.2 469.2 583.8 602.2 13597.0 13644.5 13699.1 13700.3 13703.2 12026.0 12078.9 12117.5 12367.4 12493.6 10328.2 10417.6 10481.6 11761.5 11940.8 3236.9 3252.2 3291.6 3301.8 3379.4 65360.0 65946.7 66392.7 66672.3 66853.0 8560.6 8625.6 8808.2 8955.3 8971.4 17027.0 17614.0 17894.0 18002.1 18161.5 6546.8 6912.0 6935.9 7072.2 7084.4 3583.0 3652.3 3653.4 3667.8 3694.2 2853.7 2910.0 2937.6 2940.2 2945.7 503.6 504.1 516.8 520.9 522.9 503.6 545.2 548.6 580.7 606.2 8144.8 8343.9 8458.7 8491.0 8522.7 454.3 547.9 562.1 599.1 611.5 3790.5 4065.3 4084.0 4092.8 4155.1 64093.7 64628.7 66334.3 67485.0 68511.0 56896.1 57411.4 59472.1 59931.7 59938.7 52535.0 52575.7 52637.7 53069.7 53218.7 7450.0 7848.3 7907.1 8023.8 8095.1 37062.8 37316.0 37392.0 37408.3 37685.2 42138.0 47534.8 48853.0 49135.8 50665.0 9272.6 9418.0 9616.9 9952.6 9982.0 11187.2 11278.7 11658.0 11817.6 12096.5 10685.1 10894.7 10994.8 11070.9 11478.2 10616.9 10803.7 11040.0 11231.6 11378.8 10161.2 10483.5 10722.2 10910.2 11071.4 9075.2 9091.8 9116.1 9232.4 9383.0 8905.9 9260.9 9285.0 9287.9 9504.5 12:25:13 INFO - PROCESS | 1109 | -------- Summary: end -------- 12:25:13 INFO - PROCESS | 1109 | 12:25:13 INFO - TEST-INFO | 1109: exit 0 12:25:13 INFO - TEST-OK | dromaeo_css | took 1122186ms 12:25:13 INFO - TEST-START | kraken 12:25:13 INFO - operating with platform_type : linux_ 12:25:13 INFO - Initialising browser for kraken test... 12:25:13 INFO - TEST-INFO | started process 1487 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpe3Ztf0/profile http://localhost:60920/getInfo.html) 12:25:17 INFO - PROCESS | 1487 | __metrics Screen width/height:1600/1200 12:25:17 INFO - PROCESS | 1487 | colorDepth:24 12:25:17 INFO - PROCESS | 1487 | Browser inner width/height: 1024/697 12:25:17 INFO - PROCESS | 1487 | __metrics 12:25:18 INFO - PROCESS | 1487 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:25:18 INFO - PROCESS | 1487 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:25:19 INFO - PROCESS | 1487 | console.error: PushService: 12:25:19 INFO - PROCESS | 1487 | stateChangeProcessEnqueue: Error transitioning state 12:25:19 INFO - PROCESS | 1487 | UnknownError 12:25:19 INFO - TEST-INFO | 1487: exit 0 12:25:19 INFO - Browser initialized. 12:25:19 INFO - Running cycle 1/1 for kraken test... 12:25:19 INFO - TEST-INFO | started process 1574 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpe3Ztf0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:25:31 INFO - PROCESS | 1574 | RSS: Main: 155467776 12:25:31 INFO - PROCESS | 1574 | 12:25:34 INFO - PROCESS | 1574 | Cycle 1(1): loaded http://localhost:60920/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60920/tests/kraken/driver.html?testName=audio-beat-detection) 12:25:35 INFO - PROCESS | 1574 | RSS: Main: 209416192 12:25:35 INFO - PROCESS | 1574 | 12:25:38 INFO - PROCESS | 1574 | Cycle 1(1): loaded http://localhost:60920/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60920/tests/kraken/driver.html?testName=audio-dft) 12:25:38 INFO - PROCESS | 1574 | RSS: Main: 187645952 12:25:38 INFO - PROCESS | 1574 | 12:25:41 INFO - PROCESS | 1574 | Cycle 1(1): loaded http://localhost:60920/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60920/tests/kraken/driver.html?testName=audio-fft) 12:25:42 INFO - PROCESS | 1574 | RSS: Main: 269877248 12:25:42 INFO - PROCESS | 1574 | 12:25:44 INFO - PROCESS | 1574 | Cycle 1(1): loaded http://localhost:60920/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60920/tests/kraken/driver.html?testName=audio-oscillator) 12:25:45 INFO - PROCESS | 1574 | RSS: Main: 180961280 12:25:45 INFO - PROCESS | 1574 | 12:25:47 INFO - PROCESS | 1574 | Cycle 1(1): loaded http://localhost:60920/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60920/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:25:48 INFO - PROCESS | 1574 | RSS: Main: 184569856 12:25:48 INFO - PROCESS | 1574 | 12:25:51 INFO - PROCESS | 1574 | Cycle 1(1): loaded http://localhost:60920/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60920/tests/kraken/driver.html?testName=imaging-darkroom) 12:25:51 INFO - PROCESS | 1574 | RSS: Main: 224141312 12:25:51 INFO - PROCESS | 1574 | 12:25:55 INFO - PROCESS | 1574 | Cycle 1(1): loaded http://localhost:60920/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60920/tests/kraken/driver.html?testName=imaging-desaturate) 12:25:55 INFO - PROCESS | 1574 | RSS: Main: 217640960 12:25:55 INFO - PROCESS | 1574 | 12:25:58 INFO - PROCESS | 1574 | Cycle 1(1): loaded http://localhost:60920/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60920/tests/kraken/driver.html?testName=json-parse-financial) 12:25:59 INFO - PROCESS | 1574 | RSS: Main: 219922432 12:25:59 INFO - PROCESS | 1574 | 12:26:01 INFO - PROCESS | 1574 | Cycle 1(1): loaded http://localhost:60920/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60920/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:26:01 INFO - PROCESS | 1574 | RSS: Main: 182980608 12:26:01 INFO - PROCESS | 1574 | 12:26:03 INFO - PROCESS | 1574 | Cycle 1(1): loaded http://localhost:60920/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60920/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:26:04 INFO - PROCESS | 1574 | RSS: Main: 190910464 12:26:04 INFO - PROCESS | 1574 | 12:26:06 INFO - PROCESS | 1574 | Cycle 1(1): loaded http://localhost:60920/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60920/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:26:07 INFO - PROCESS | 1574 | RSS: Main: 198828032 12:26:07 INFO - PROCESS | 1574 | 12:26:09 INFO - PROCESS | 1574 | Cycle 1(1): loaded http://localhost:60920/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60920/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:26:10 INFO - PROCESS | 1574 | RSS: Main: 203587584 12:26:10 INFO - PROCESS | 1574 | 12:26:13 INFO - PROCESS | 1574 | Cycle 1(1): loaded http://localhost:60920/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60920/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:26:14 INFO - PROCESS | 1574 | RSS: Main: 204435456 12:26:14 INFO - PROCESS | 1574 | 12:26:16 INFO - PROCESS | 1574 | Cycle 1(1): loaded http://localhost:60920/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60920/tests/kraken/driver.html?testName=ai-astar) 12:26:16 INFO - PROCESS | 1574 | RSS: Main: 256188416 12:26:16 INFO - PROCESS | 1574 | 12:26:16 INFO - PROCESS | 1574 | __start_tp_report 12:26:16 INFO - PROCESS | 1574 | _x_x_mozilla_page_load 12:26:16 INFO - PROCESS | 1574 | _x_x_mozilla_page_load_details 12:26:16 INFO - PROCESS | 1574 | |i|pagename|runs| 12:26:16 INFO - PROCESS | 1574 | |0;ai-astar;107;116;119;116;115;115;116;115;117;115 12:26:16 INFO - PROCESS | 1574 | |1;audio-beat-detection;157;125;125;165;125;128;127;123;143;126 12:26:16 INFO - PROCESS | 1574 | |2;audio-dft;161;162;162;162;161;162;161;161;162;161 12:26:16 INFO - PROCESS | 1574 | |3;audio-fft;76;76;77;110;79;78;76;77;91;76 12:26:16 INFO - PROCESS | 1574 | |4;audio-oscillator;84;84;110;85;85;85;101;84;85;105 12:26:16 INFO - PROCESS | 1574 | |5;imaging-gaussian-blur;127;128;127;128;128;128;127;127;127;128 12:26:16 INFO - PROCESS | 1574 | |6;imaging-darkroom;147;147;147;147;147;148;147;147;147;147 12:26:16 INFO - PROCESS | 1574 | |7;imaging-desaturate;95;95;95;95;95;95;95;96;95;95 12:26:16 INFO - PROCESS | 1574 | |8;json-parse-financial;66;64;65;64;64;65;64;65;67;74 12:26:16 INFO - PROCESS | 1574 | |9;json-stringify-tinderbox;66;67;66;66;67;66;66;66;66;66 12:26:16 INFO - PROCESS | 1574 | |10;stanford-crypto-aes;84;85;84;83;84;84;84;84;83;85 12:26:16 INFO - PROCESS | 1574 | |11;stanford-crypto-ccm;149;136;134;134;136;134;142;141;228;154 12:26:16 INFO - PROCESS | 1574 | |12;stanford-crypto-pbkdf2;188;178;182;178;178;179;181;181;184;180 12:26:16 INFO - PROCESS | 1574 | |13;stanford-crypto-sha256-iterative;71;68;67;69;68;67;68;68;68;67 12:26:16 INFO - PROCESS | 1574 | __end_tp_report 12:26:16 INFO - PROCESS | 1574 | __start_cc_report 12:26:16 INFO - PROCESS | 1574 | _x_x_mozilla_cycle_collect,1975 12:26:16 INFO - PROCESS | 1574 | __end_cc_report 12:26:16 INFO - PROCESS | 1574 | __startTimestamp1482179176438__endTimestamp 12:26:16 INFO - PROCESS | 1574 | 12:26:16 INFO - PROCESS | 1574 | ------- Summary: start ------- 12:26:16 INFO - PROCESS | 1574 | Number of tests: 14 12:26:16 INFO - PROCESS | 1574 | 12:26:16 INFO - PROCESS | 1574 | [#0] ai-astar Cycles:10 Average:115.10 Median:116.00 stddev:3.11 (2.7%) stddev-sans-first:1.32 12:26:16 INFO - PROCESS | 1574 | Values: 107.0 116.0 119.0 116.0 115.0 115.0 116.0 115.0 117.0 115.0 12:26:16 INFO - PROCESS | 1574 | 12:26:16 INFO - PROCESS | 1574 | [#1] audio-beat-detection Cycles:10 Average:134.40 Median:127.50 stddev:15.21 (11.9%) stddev-sans-first:13.76 12:26:16 INFO - PROCESS | 1574 | Values: 157.0 125.0 125.0 165.0 125.0 128.0 127.0 123.0 143.0 126.0 12:26:16 INFO - PROCESS | 1574 | 12:26:16 INFO - PROCESS | 1574 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:0.53 (0.3%) stddev-sans-first:0.53 12:26:16 INFO - PROCESS | 1574 | Values: 161.0 162.0 162.0 162.0 161.0 162.0 161.0 161.0 162.0 161.0 12:26:16 INFO - PROCESS | 1574 | 12:26:16 INFO - PROCESS | 1574 | [#3] audio-fft Cycles:10 Average:81.60 Median:77.00 stddev:10.97 (14.2%) stddev-sans-first:11.44 12:26:16 INFO - PROCESS | 1574 | Values: 76.0 76.0 77.0 110.0 79.0 78.0 76.0 77.0 91.0 76.0 12:26:16 INFO - PROCESS | 1574 | 12:26:16 INFO - PROCESS | 1574 | [#4] audio-oscillator Cycles:10 Average:90.80 Median:84.50 stddev:10.26 (12.1%) stddev-sans-first:10.58 12:26:16 INFO - PROCESS | 1574 | Values: 84.0 84.0 110.0 85.0 85.0 85.0 101.0 84.0 85.0 105.0 12:26:16 INFO - PROCESS | 1574 | 12:26:16 INFO - PROCESS | 1574 | [#5] imaging-gaussian-blur Cycles:10 Average:127.50 Median:128.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 12:26:16 INFO - PROCESS | 1574 | Values: 127.0 128.0 127.0 128.0 128.0 128.0 127.0 127.0 127.0 128.0 12:26:16 INFO - PROCESS | 1574 | 12:26:16 INFO - PROCESS | 1574 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 12:26:16 INFO - PROCESS | 1574 | Values: 147.0 147.0 147.0 147.0 147.0 148.0 147.0 147.0 147.0 147.0 12:26:16 INFO - PROCESS | 1574 | 12:26:16 INFO - PROCESS | 1574 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 12:26:16 INFO - PROCESS | 1574 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 12:26:16 INFO - PROCESS | 1574 | 12:26:16 INFO - PROCESS | 1574 | [#8] json-parse-financial Cycles:10 Average:65.80 Median:65.00 stddev:3.05 (4.7%) stddev-sans-first:3.23 12:26:16 INFO - PROCESS | 1574 | Values: 66.0 64.0 65.0 64.0 64.0 65.0 64.0 65.0 67.0 74.0 12:26:16 INFO - PROCESS | 1574 | 12:26:16 INFO - PROCESS | 1574 | [#9] json-stringify-tinderbox Cycles:10 Average:66.20 Median:66.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 12:26:16 INFO - PROCESS | 1574 | Values: 66.0 67.0 66.0 66.0 67.0 66.0 66.0 66.0 66.0 66.0 12:26:16 INFO - PROCESS | 1574 | 12:26:16 INFO - PROCESS | 1574 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:84.00 stddev:0.67 (0.8%) stddev-sans-first:0.71 12:26:16 INFO - PROCESS | 1574 | Values: 84.0 85.0 84.0 83.0 84.0 84.0 84.0 84.0 83.0 85.0 12:26:16 INFO - PROCESS | 1574 | 12:26:16 INFO - PROCESS | 1574 | [#11] stanford-crypto-ccm Cycles:10 Average:148.80 Median:141.50 stddev:28.66 (20.3%) stddev-sans-first:30.40 12:26:16 INFO - PROCESS | 1574 | Values: 149.0 136.0 134.0 134.0 136.0 134.0 142.0 141.0 228.0 154.0 12:26:16 INFO - PROCESS | 1574 | 12:26:16 INFO - PROCESS | 1574 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.90 Median:181.00 stddev:3.18 (1.8%) stddev-sans-first:2.09 12:26:16 INFO - PROCESS | 1574 | Values: 188.0 178.0 182.0 178.0 178.0 179.0 181.0 181.0 184.0 180.0 12:26:16 INFO - PROCESS | 1574 | 12:26:16 INFO - PROCESS | 1574 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:1.20 (1.8%) stddev-sans-first:0.67 12:26:16 INFO - PROCESS | 1574 | Values: 71.0 68.0 67.0 69.0 68.0 67.0 68.0 68.0 68.0 67.0 12:26:16 INFO - PROCESS | 1574 | -------- Summary: end -------- 12:26:16 INFO - PROCESS | 1574 | 12:26:17 INFO - TEST-INFO | 1574: exit 0 12:26:17 INFO - TEST-OK | kraken | took 63693ms 12:26:17 INFO - SUITE-END | took 1185s 12:26:18 INFO - Completed test suite (00:19:46) 12:26:18 INFO - javascript benchmark 12:26:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2558.462722828206, 2634.5126151661957, 2662.467506498701, 2681.0750291149557, 2683.6511253537037, 4621.514740952202, 4638.072385522901, 4763.190257656198, 4854.36893203883, 4880.243562247849, 4744.596709279706, 4786.186348320785, 4797.472278049412, 4821.8794530136765, 4907.877303067425, 4309.784510774464, 4354.934675979863, 4359.80380882861, 4421.027373977736, 4471.4187155669715, 4325.913481730364, 4364.25807612705, 4398.054418300065, 4415.492218394877, 4468.486124095722, 4412.97793511032, 4415.51429342772, 4422.380866678667, 4449.243628583133, 4463.531329210435, 4288.807003684841, 4305.418768466248, 4305.763183024932, 4338.761368124752, 4440.800163992628, 4382.780860956939, 4386.254336762758, 4386.429764130675, 4425.601695847389, 4443.42235509385, 4813.157697402976, 4846.079244943449, 4846.321514987904, 4866.318715379814, 4870.366852309213, 4911.336969509142, 4946.233333833262, 5226.608004399685, 5233.712145831978, 5264.684118952875, 5041.873953151164, 5076.593872490192, 5099.949000509984, 5143.5113664201845, 5220.060389129993, 4804.039192161582, 4816.807327706888, 4817.590504807061, 4824.975875120672, 4865.099956508057, 5279.287296215039, 5285.471452854684, 5294.152935530296, 5296.682199068068, 5298.81454149103, 4922.827701030518, 4968.354113965162, 4970.304157417964, 4992.326035985168, 5036.194208926554, 5115.155999260155, 5137.023965446628, 5143.048536020848, 5182.0, 5195.246689230041, 5055.064813009605, 5096.821611243588, 5112.233165025202, 5155.510226528474, 5156.123459011977, 4899.191633380524, 4927.975360123177, 4935.062338155739, 5057.190849464068, 5096.490350964874, 4246.363045543131, 4277.486701595828, 4278.507971583245, 4304.332828411601, 4348.195583817004, 3758.530183727034, 3769.754965927206, 3773.8867833965023, 3802.7908465034134, 3803.5435747710453, 4290.549492303326, 4419.580139886705, 4421.292593185075, 4426.933595996063, 4457.487388950312, 4999.975000125049, 5065.341505604248, 5085.771140298695, 5097.8215762448135, 5167.509086636764, 4385.386045953569, 4387.802548885308, 4388.144311859221, 4393.494748103945, 4437.090396468716, 4562.84030058953, 4572.314152877095, 4574.4510658721165, 4577.1074640444795, 4619.630429565626, 4655.255159174651, 4665.32352808834, 4668.5798278155125, 4709.222978208557, 4720.905581888412, 5120.103981803095, 5127.2052831811125, 5130.281760553451, 5141.434442211428, 5193.584513238856, 4774.904501909873, 4779.593734532465, 4781.450133234723, 4785.090832741768, 4832.492588278321, 4824.204006339055, 4848.151573474698, 5022.4977502249485, 5033.094043072282, 5080.7205603692155, 5085.923711144262, 5143.61422893292, 5147.510986456281, 5195.272661827423, 5207.453217412269, 5024.824131155392, 5033.169527028, 5034.823781167642, 5039.0929632666475, 5081.187010078517, 4753.524647535219, 4788.497207793132, 5025.6482046256415, 5030.62270329734, 5038.949610503848, 4920.926186107283, 4946.233333833262, 4953.133201689762, 4977.328060711758, 5029.044481548494, 5030.949690503048, 5053.898922021465, 5057.165567681292, 5065.265536497261, 5119.846404607868, 4783.617310615073, 4814.927776083432, 4819.3012013258585, 4841.176999910094, 4878.121938051043], "value": 4685.2439371099235, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.95680388765015, 525.9079661059313, 554.8917960997604, 559.4433538629065, 565.3329071695398, 35812.641873581255, 38025.42961855575, 38053.429198562044, 38145.80927095367, 38187.427188592126, 38100.0, 38615.0, 38635.61364386355, 38681.41977870327, 38699.41950870739, 7272.527285726411, 7487.43844211685, 7705.9596954411, 7708.03649543807, 7709.922900771005, 7225.349718525332, 9628.855567166498, 9874.1113299803, 9878.0, 10193.796124077515, 9527.571259293349, 9559.713208603753, 9567.904320956806, 9575.281853860954, 9575.664851730191, 9719.0, 9740.561674724619, 9768.853467197998, 9832.508374581243, 9832.606695732162, 9708.805823883482, 9783.168430683329, 9784.657536986242, 9785.657501987467, 9785.706428807147, 36023.63976360229, 36410.45384319211, 36451.4532282016, 36453.63546364529, 36541.269174616624, 2781.735735105162, 2832.3344014156655, 2939.2945693033516, 2952.8818847246084, 3274.5088236764436, 26241.21276361693, 26535.336616584547, 26662.20013399582, 27305.180844574694, 27461.588076178872, 3269.4605390110605, 3346.0631023313513, 3373.2747459296124, 3436.226848958964, 3469.60099588548, 246858.0, 247000.0, 247591.0, 248312.0, 248312.0, 43530.7823460887, 44119.55880441154, 44123.0, 44169.11661766747, 44188.77905610516, 46942.0, 46956.0, 46973.5302646976, 47000.294995575096, 47013.76493117581, 12603.243805371707, 12877.291748953898, 12890.871091288967, 12896.226226426257, 12903.290319032543, 8921.955390223136, 9156.221721153643, 9409.435433873989, 9433.811323773487, 9901.603935842486, 11864.288142711464, 12011.219270747548, 12151.392430378623, 12178.0, 12255.0, 6154.599951003171, 6363.172787537591, 6458.867449287092, 6503.894337962558, 6535.673216339164, 757.9166291707908, 877.7761670774039, 962.2542529539663, 990.5195979949718, 1008.3344992305043, 26316.0, 26950.191494255203, 27104.1868743938, 27129.86435067812, 27322.316942076606, 880.8678698195322, 894.1550235027967, 947.8056998315329, 985.6944347252374, 998.1116806042628, 13364.55691146212, 14194.290285485684, 14500.854991450162, 14511.637209069646, 14552.85447145536, 342637.0, 344071.0, 344101.0, 345117.0, 346957.0, 46895.76552117217, 47159.29261061087, 47412.288815667795, 47412.76293618579, 47540.5245947543, 985.3545927417553, 1062.490004797686, 1078.0890147825075, 1079.3577821196375, 1146.8853114688463, 25701.742982570682, 27443.86278068597, 27751.861240694474, 29644.258893527833, 30430.695693042788, 43755.124897501235, 44165.11669766651, 44384.55615443804, 44413.33379999367, 44434.11178273402, 242041.0, 243980.0, 244599.0, 245095.39236861365, 245275.0, 21287.0, 21916.23293184731, 23182.76817231806, 23359.53280934406, 23755.1685691, 33469.66530334666, 35061.64938350686, 36061.81969090138, 36079.27841443209, 36132.63867361293, 29151.854240728662, 29152.0, 29722.702772971992, 29737.553936690536, 30018.099457016324, 25665.27380690574, 26287.868560657073, 26501.469970600865, 26764.330891727863, 27048.0, 30551.388972220877, 30572.541411879283, 30618.540721889636, 30825.691743083178, 30974.22564435907, 25555.744442555337, 25565.87217063977, 28272.575911360935, 28397.432051358443, 28438.0, 21488.355349339545, 21935.341939741196, 22173.778262217173, 22239.22162724297, 22252.88873555622], "value": 16345.010895623971, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.76444162675404, 359.8668492657717, 363.3786225554302, 366.1267876115464, 379.33616171699526, 12523.24860508369, 15450.536483905487, 15653.217339133054, 15717.0, 16608.501744947673, 15328.0, 15465.30406131724, 15738.0, 15816.76274855878, 16044.432890737484, 3664.578573464046, 4416.227160246952, 4548.5224051474615, 4552.0, 4578.2903649934315, 3413.76103672743, 3575.6424357564165, 4440.866773996785, 4523.00493891345, 5104.489551044903, 5332.0, 5433.673979561239, 5433.891322173554, 5434.429384914567, 5438.510534051935, 5248.422673505911, 5386.272853164812, 5421.918671219935, 5436.700981446019, 5442.183672449125, 5242.9213561796605, 5247.449017853147, 5247.711375874325, 5247.868803279948, 5250.133727934886, 12021.759564808743, 17009.0, 19292.517687057796, 19410.22359105634, 19496.220151193935, 1663.9001659900323, 1668.5661727950699, 1742.128935532234, 1855.22080726094, 2253.6799062532436, 8360.540170290693, 8397.580120993925, 8630.439021463584, 8677.739667809912, 8782.25350845179, 1660.3109709470555, 1692.1285537947967, 1738.3568079810425, 1756.1745979389666, 1763.8588912886937, 64478.17799134281, 66544.3345566543, 66593.0, 67809.0, 67822.0, 9723.416595004323, 9877.209823214125, 9879.0, 10046.146077583488, 10153.340032897839, 13700.45198192061, 13709.0, 13716.177029378268, 13740.381682824296, 13751.449942002208, 6734.158230221223, 6909.585424874524, 6931.584104953719, 7104.822379440556, 7106.289371062852, 6537.509686773511, 6546.509011824037, 6698.33016698336, 6717.966410167918, 6749.966250168816, 4277.743335399824, 4301.569843015736, 4306.911148093172, 4360.236958532244, 4404.2512772828695, 1663.3928616055296, 1780.8219178082347, 1828.7988321284647, 1896.7344571759966, 1899.5725961658516, 680.210955291859, 753.6081237756335, 754.7773406844993, 755.935745461631, 818.4761752478419, 15645.765313520305, 15744.763828542582, 15825.683486330208, 15997.200139992954, 16749.162541872858, 830.464350493928, 846.3609974469236, 846.6105591428012, 866.536403024379, 883.5847151838625, 8833.116688331116, 8899.955500222457, 9324.813503729887, 9336.39313444624, 9415.576299066559, 333340.0, 339588.0, 340129.0, 341661.0, 347317.0, 70923.64538177276, 71568.0, 71735.6413217941, 72464.27535724576, 72636.27363726294, 868.7377664268924, 870.1994165367877, 896.7444278380631, 903.074348792493, 904.8778414913902, 14135.293235338399, 14204.78692819588, 14317.713645727235, 14355.354009069408, 14931.402743890123, 34722.0, 35010.29979400449, 35322.47016294809, 35826.28347433089, 36009.0, 47322.29016564685, 47371.52628473671, 47391.0, 47466.76266618645, 47776.28335575038, 7371.189169191277, 7412.0, 7468.551886886804, 7525.623718814147, 7855.7250496232355, 8464.95767521183, 10941.835872461761, 11559.19085664029, 11682.88317116852, 11935.104867135173, 5326.467353264643, 5516.200150978006, 9662.502312141629, 9677.032296770267, 10154.492275386348, 9483.383580067273, 9739.513024348897, 9976.401415915068, 10034.247431442816, 10132.493375331056, 9741.951290243504, 10212.795744085226, 10233.130183934449, 10412.895871041495, 10524.842127367943, 7259.782206533812, 7328.670209840463, 7340.449466289942, 7423.888641670272, 7431.29402706742, 8520.659173632985, 9759.853602196115, 10142.391456512632, 10177.134943529587, 10448.582056717647], "value": 7722.10677519298, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1350.6960933789896, 1365.3719289127005, 1462.4881291547954, 1596.106180538899, 1671.0558534428046, 6658.800235992922, 7113.715451381951, 7121.394681452083, 7122.537035092715, 7128.394086502647, 6319.873602527959, 6514.7719829805965, 6731.899021514681, 6954.687039083241, 6999.335063169004, 2124.33083578673, 2165.8592191507582, 2168.2844661261825, 2168.382011126829, 2169.9132034718673, 2085.718428012214, 2127.4362293992103, 2173.0, 2189.770074142216, 2194.36363454598, 2076.9896150519303, 2081.261152290938, 2103.9894800526054, 2108.219958615307, 2111.2082968886666, 2077.3352527191305, 2095.6646936490088, 2098.339023207692, 2099.916003359879, 2101.957960840775, 2059.258666879922, 2080.032784755081, 2080.178329559815, 2086.9269575564936, 2090.3833369156137, 7190.209077001526, 7234.168070671887, 7304.160021597478, 7308.598027108507, 7316.048913641246, 4267.295896177127, 4394.956050439519, 4402.515723270438, 4407.647388208936, 4408.382826404306, 2737.6441062661925, 2866.2977570495364, 2905.0558568465335, 2907.8109922854946, 2913.737763601286, 860.8493513635151, 896.9955150224207, 900.9549522523847, 909.522500687138, 1679.8404151605578, 9070.0, 9480.857787133198, 9495.14543691071, 9506.95246523763, 9513.048695130501, 3725.2921944830396, 3887.8056097195026, 3925.548561915359, 3946.8026598669953, 3949.605039496085, 7490.4382171337365, 7499.437542184358, 7509.098908131059, 7510.699572017167, 7521.435892307989, 4392.648588112943, 4392.802323895432, 4394.055278115221, 4395.362672412482, 4399.62603178727, 3569.196930690574, 3697.131174174067, 3969.166475040274, 4091.959080409217, 4097.549269580344, 3141.1204862638497, 3168.287135394564, 3261.3314270574474, 3309.7848639838335, 3310.7351411886993, 987.9993014146411, 1005.7636455432968, 1010.4594042187395, 1010.5048526222246, 1011.7723512209841, 824.2817185384253, 836.6653338664582, 838.0975186327125, 860.1957170046027, 879.8812160358398, 4820.861836172371, 4913.901721965541, 4956.479569645207, 5096.719680417538, 5128.102582048126, 398.2413502278165, 407.893947573633, 418.2158452900811, 423.6547214020625, 425.06485731391064, 3388.15296175956, 3591.075034567813, 3781.829817658212, 3785.3186426442953, 3801.695864330902, 8505.10696376884, 8703.825923481621, 8795.428297160664, 8802.471851688919, 8815.118488151134, 8779.078196789245, 8782.692605758768, 8785.604647790737, 8791.120887911158, 8797.47215167092, 6565.409113179837, 6713.731450741916, 6764.72941082361, 6822.897656535156, 6850.862982740317, 4920.6801557898625, 4958.0, 5068.442471328225, 5102.617303702312, 5200.323957885451, 7618.542887426772, 7715.498492597963, 7769.028871391076, 7793.688252470064, 7994.0, 7985.281324680806, 8002.879956800769, 8025.0, 8044.195580442143, 8055.07366652819, 6161.9691901542, 6197.256329240339, 6229.96885015572, 6256.906146407717, 6261.812145635638, 6877.243503214742, 6948.965255173692, 6954.826129346807, 6957.965210173917, 6961.651917404009, 6228.906566401598, 6326.936730632819, 6337.081123237197, 6343.143675603919, 6501.9674901625185, 6006.549508786946, 6092.421219984094, 6180.165677633642, 6219.657918814509, 6348.0, 5656.943430565806, 5756.309242890712, 5758.021136406737, 5764.740586673526, 5766.452187042224, 4430.889227769203, 4457.955420445754, 4514.774261286988, 4618.053299073749, 4650.48844627084, 4351.260285751493, 4372.278574035248, 4520.2541580640145, 4587.541245875385, 4655.18534256497], "value": 3820.7304038680954, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.6713089695686, 351.371045827968, 357.37459445969546, 357.49771570942835, 359.3567514149672, 4826.155422801009, 4835.5406236407525, 4882.145624515713, 4899.828506002291, 4901.862807803009, 4573.3825933498965, 4800.183968725316, 4808.062427826575, 4824.493428190042, 4826.107170173512, 3728.384816505287, 3739.8130093495215, 3767.3595488766973, 3778.4332350147424, 3793.468914351993, 3784.3944968804994, 3788.886333410002, 3795.3168429682783, 3799.7150213733944, 3808.428735689641, 3781.243751249747, 3785.599328248552, 3788.015116069814, 3793.468914351993, 3796.4495148203496, 3629.6370362963758, 3711.5546134463766, 3745.4569087482164, 3803.353429916893, 3803.5356387790625, 3417.7778444401033, 3425.06483836634, 3429.4855771634207, 3434.4848272759286, 3448.2241495663275, 4679.415073115861, 4699.318598803188, 4700.55344742249, 4702.976485117587, 4726.338312636233, 4032.959670403288, 4202.789860506962, 4388.868333949987, 4397.890052748675, 4400.713953593006, 5013.874653133665, 5019.3976722793495, 5026.84919452417, 5028.673136246169, 5041.386756238006, 3533.0, 3535.1515636247113, 3541.3802584547857, 3544.6278140795102, 3549.396602577568, 5059.544640982329, 5245.632805703603, 5323.228131920889, 5323.760430780624, 5327.813526526553, 3596.28074385124, 3609.296187243462, 3611.855525778992, 3624.1697942539377, 3629.3305079663123, 3666.5966743658178, 3670.8164591770305, 3675.852965881335, 3679.374506333876, 3709.5177626908867, 4216.0, 4220.894477638084, 4227.852025179165, 4238.385434112037, 4251.787410629456, 4947.738326726733, 5008.622628777115, 5044.9495505045215, 5075.670081444694, 5094.821681241213, 1223.473906220334, 1227.5028613411582, 1230.8767376275853, 1235.832138629005, 1240.9565665201674, 801.7394346837301, 822.0710597025443, 846.3102571404286, 857.4683696108452, 857.8627419612832, 4210.705250632426, 4210.726302790309, 4232.492100947906, 4235.576442355739, 4240.300350442142, 5083.262926875656, 5144.794208231704, 5172.586193104543, 5173.81891633791, 5198.870028249324, 4340.52254252032, 4456.175607512556, 4460.754658493749, 4464.799084041226, 4464.977675111603, 2669.636258314538, 2693.4074503609177, 2708.512467755823, 2718.904838330649, 2724.9727502725113, 4757.857264282077, 4761.381020467386, 4761.976190119027, 4773.355596994361, 4794.664373493892, 5028.823991160292, 5151.728652305738, 5196.986587615379, 5200.323957885526, 5203.973980130075, 4871.8294859679745, 4885.511448855157, 4901.411830580353, 4902.14212512816, 4910.8281210157475, 5129.204973229155, 5136.486351364983, 5136.845894623168, 5190.714510701948, 5232.555232805102, 4933.235348520985, 5070.315507406601, 5100.48995100487, 5112.284280200849, 5165.070287348313, 4878.682885612424, 4888.535589119028, 4894.608431325414, 4895.33912921751, 4898.632602554751, 3642.1805093853673, 3657.311875771359, 3676.724245681638, 3677.7679477375377, 3691.2986532559803, 4998.475160108136, 5257.763400646903, 5267.578593712417, 5272.789088436573, 5279.0, 5000.874978125576, 5041.117804384291, 5056.595472362275, 5066.695998240118, 5073.949260507496, 5060.772265247999, 5071.17437722427, 5080.212567052113, 5093.745312734422, 5110.71891045996, 4839.95160048395, 5009.7244651544515, 5025.321581586439, 5030.974845125751, 5037.722925239001, 4856.63575231852, 4907.852764417074, 4963.602911766978, 5074.0, 5096.566791822588, 4958.355413796184, 4983.377077865267, 4983.9003219934675, 4991.47589503097, 4999.10016197088], "value": 3804.0071978548913, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [610.2250142319255, 617.62633606668, 625.7278084033446, 644.3119028146256, 736.7274108579822, 12489.0, 14400.135991840483, 14930.850691493082, 14975.0, 15004.249787510635, 14551.490697825579, 14615.63460913475, 14689.265536723147, 14763.335649895753, 14777.556673299792, 585.7188549496244, 600.807741522713, 758.116793935065, 782.3975538835093, 784.6037750935769, 756.440234226671, 778.4745296924582, 785.4541093939714, 790.154534647927, 792.5601291283335, 831.2145022953312, 831.5675848558745, 832.6128350317101, 833.0503226321998, 833.1876420490004, 609.7713357490941, 628.7296462521114, 817.456391499655, 817.5503473089807, 818.2226884459787, 468.95779379855634, 469.19064613541707, 469.2257774671785, 583.8423625621059, 602.2351613450936, 13597.0, 13644.522441714493, 13699.109557878706, 13700.314984250748, 13703.177809331371, 12026.0, 12078.939605301917, 12117.454714537867, 12367.443465044005, 12493.564784501463, 10328.173746100294, 10417.5832966682, 10481.63314284004, 11761.529538818437, 11940.820887686692, 3236.9028929131928, 3252.235724604716, 3291.588551431071, 3301.8018918864946, 3379.425497665403, 65360.0, 65946.67026664836, 66392.66803665951, 66672.3332766671, 66853.0, 8560.61477233526, 8625.611847466878, 8808.163224493663, 8955.328350373618, 8971.372003959661, 17027.0, 17614.0, 17894.0, 18002.099895005198, 18161.45515634533, 6546.8363290918105, 6912.0, 6935.861282774316, 7072.186698529632, 7084.397826184832, 3582.964170358263, 3652.3243200008083, 3653.415453527423, 3667.7615954963376, 3694.242680250542, 2853.671827739795, 2909.985450072736, 2937.55936609506, 2940.1914473519955, 2945.6759756426777, 503.58957449678394, 504.1253425307089, 516.7906997665951, 520.8593679706458, 522.9215617657305, 503.5543543963619, 545.2094463028625, 548.6022633590615, 580.6545105662209, 606.1665210335789, 8144.75565733029, 8343.87357706714, 8458.70166225743, 8491.0, 8522.70170544028, 454.2981094209454, 547.8712502561895, 562.1174755633698, 599.1192946368831, 611.48024179448, 3790.5451345838123, 4065.288574499451, 4083.953474452879, 4092.7544347339253, 4155.085881106152, 64093.67953160204, 64628.67685661541, 66334.33665663282, 67484.98772518417, 68511.0, 56896.146557801665, 57411.425885741446, 59472.10791838126, 59931.70034149801, 59938.70030649819, 52535.0, 52575.737121314145, 52637.7368113157, 53069.73465132649, 53218.73390633099, 7450.0, 7848.332891704269, 7907.130215676386, 8023.83952320962, 8095.109537950703, 37062.814685926394, 37316.0, 37392.0, 37408.251834962604, 37685.2462950734, 42138.0, 47534.76232618953, 48853.0, 49135.75432122959, 50665.0, 9272.582733777132, 9417.952910235404, 9616.855747163938, 9952.601895924083, 9981.950090249502, 11187.216894817284, 11278.718032049264, 11658.0, 11817.645470635895, 12096.516139354679, 10685.145188385066, 10894.67315980522, 10994.785160509946, 11070.889291107307, 11478.196526243082, 10616.89383106159, 10803.675889723321, 11040.0, 11231.55073797039, 11378.829317560409, 10161.237907157141, 10483.47582620881, 10722.249442538947, 10910.181736369645, 11071.391073491035, 9075.183233509017, 9091.772705682146, 9116.1339672731, 9232.353735238468, 9383.0, 8905.910940890508, 9260.907390926004, 9284.95357523208, 9287.86068208991, 9504.47725375111], "value": 6120.325787212286, "unit": "score"}], "name": "dromaeo_css", "value": 6121.304930371478, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 119.0, 116.0, 115.0, 115.0, 116.0, 115.0, 117.0, 115.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 125.0, 125.0, 165.0, 125.0, 128.0, 127.0, 123.0, 143.0, 126.0], "value": 134.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 162.0, 161.0, 162.0, 161.0, 161.0, 162.0, 161.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 110.0, 79.0, 78.0, 76.0, 77.0, 91.0, 76.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 110.0, 85.0, 85.0, 85.0, 101.0, 84.0, 85.0, 105.0], "value": 90.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 128.0, 127.0, 128.0, 128.0, 128.0, 127.0, 127.0, 127.0, 128.0], "value": 127.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 147.0, 148.0, 147.0, 147.0, 147.0, 147.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 65.0, 64.0, 64.0, 65.0, 64.0, 65.0, 67.0, 74.0], "value": 65.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 66.0, 67.0, 66.0, 66.0, 66.0, 66.0, 66.0], "value": 66.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 83.0, 84.0, 84.0, 84.0, 84.0, 83.0, 85.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 136.0, 134.0, 134.0, 136.0, 134.0, 142.0, 141.0, 228.0, 154.0], "value": 148.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 178.0, 182.0, 178.0, 178.0, 179.0, 181.0, 181.0, 184.0, 180.0], "value": 180.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 67.0, 69.0, 68.0, 67.0, 68.0, 68.0, 68.0, 67.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1566.8999999999999, "alertThreshold": 2.0}]} 12:26:18 INFO - Return code: 0 12:26:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:26:18 INFO - # TBPL SUCCESS # 12:26:18 INFO - Running post-action listener: _resource_record_post_action 12:26:18 INFO - [mozharness: 2016-12-19 20:26:18.259475Z] Finished run-tests step (success) 12:26:18 INFO - Running post-run listener: _resource_record_post_run 12:26:18 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 383078400; Read time: 8; Write time: 211296 12:26:18 INFO - TinderboxPrint: CPU usage
13.1% 12:26:18 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 12:26:18 INFO - TinderboxPrint: I/O write bytes / time
383,078,400 / 211,296 12:26:18 INFO - TinderboxPrint: CPU idle
8,345.2 (86.9%) 12:26:18 INFO - TinderboxPrint: CPU user
1,212.7 (12.6%) 12:26:18 INFO - TinderboxPrint: Swap in / out
0 / 0 12:26:18 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 101531648; Read time: 0; Write time: 73200 12:26:18 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 281546752; Read time: 0; Write time: 138096 12:26:18 INFO - Running post-run listener: _upload_blobber_files 12:26:18 INFO - Blob upload gear active. 12:26:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:26:18 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 12:26:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:26:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:26:19 INFO - (blobuploader) - INFO - Open directory for files ... 12:26:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:26:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:26:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:26:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:26:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:26:20 INFO - (blobuploader) - INFO - Done attempting. 12:26:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:26:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:26:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:26:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:26:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:26:23 INFO - (blobuploader) - INFO - Done attempting. 12:26:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:26:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:26:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:26:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:26:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:26:24 INFO - (blobuploader) - INFO - Done attempting. 12:26:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:26:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:26:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:26:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:26:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:26:26 INFO - (blobuploader) - INFO - Done attempting. 12:26:26 INFO - (blobuploader) - INFO - Iteration through files over. 12:26:26 INFO - Return code: 0 12:26:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:26:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:26:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/47b76ad7dd31030b92bd293e45bb53301e5226dc492c577a08b815ed36a24aea06655f3b10587db985ba6385b6e6f6324d6774f2440c6c26aa790da50c0631e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2560cb4d714eb5132f01ef2069ac826ed707927d37d1e3cedcaa0e56c217b656e39852750488ca53151b5ad754a7b2522bbbbac56d1571631afb66a165820739", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d3cb49c7be0c27ba63c0cfb1d06ab6fc7ca56394b6596cd6c377f4da52a0f1075b1868b4f98b4d502f991c7786b6421e093ea9ca22f77333718207ff3b951883", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c48c5a0cc79c0b9d3dcdad59fec6ff8e79877299cca2f5f2cc5c3b7b33892fb180a32fd35d6768a8e242f0e994f57fbd8d4fa6b86d8a2da9721e2bdf6425f43a"} 12:26:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:26:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:26:26 INFO - Contents: 12:26:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/47b76ad7dd31030b92bd293e45bb53301e5226dc492c577a08b815ed36a24aea06655f3b10587db985ba6385b6e6f6324d6774f2440c6c26aa790da50c0631e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2560cb4d714eb5132f01ef2069ac826ed707927d37d1e3cedcaa0e56c217b656e39852750488ca53151b5ad754a7b2522bbbbac56d1571631afb66a165820739", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d3cb49c7be0c27ba63c0cfb1d06ab6fc7ca56394b6596cd6c377f4da52a0f1075b1868b4f98b4d502f991c7786b6421e093ea9ca22f77333718207ff3b951883", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c48c5a0cc79c0b9d3dcdad59fec6ff8e79877299cca2f5f2cc5c3b7b33892fb180a32fd35d6768a8e242f0e994f57fbd8d4fa6b86d8a2da9721e2bdf6425f43a"} 12:26:26 INFO - Running post-run listener: copy_logs_to_upload_dir 12:26:26 INFO - Copying logs to upload dir... 12:26:26 INFO - mkdir: /builds/slave/test/build/upload/logs 12:26:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.186837 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-12-19 12:26:26.160645) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-19 12:26:26.164215) ========= 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=31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/47b76ad7dd31030b92bd293e45bb53301e5226dc492c577a08b815ed36a24aea06655f3b10587db985ba6385b6e6f6324d6774f2440c6c26aa790da50c0631e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2560cb4d714eb5132f01ef2069ac826ed707927d37d1e3cedcaa0e56c217b656e39852750488ca53151b5ad754a7b2522bbbbac56d1571631afb66a165820739", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d3cb49c7be0c27ba63c0cfb1d06ab6fc7ca56394b6596cd6c377f4da52a0f1075b1868b4f98b4d502f991c7786b6421e093ea9ca22f77333718207ff3b951883", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c48c5a0cc79c0b9d3dcdad59fec6ff8e79877299cca2f5f2cc5c3b7b33892fb180a32fd35d6768a8e242f0e994f57fbd8d4fa6b86d8a2da9721e2bdf6425f43a"} build_url:https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011806 build_url: 'https://queue.taskcluster.net/v1/task/FmfgxYbKQNOcUe6YCTpV-g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/47b76ad7dd31030b92bd293e45bb53301e5226dc492c577a08b815ed36a24aea06655f3b10587db985ba6385b6e6f6324d6774f2440c6c26aa790da50c0631e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2560cb4d714eb5132f01ef2069ac826ed707927d37d1e3cedcaa0e56c217b656e39852750488ca53151b5ad754a7b2522bbbbac56d1571631afb66a165820739", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d3cb49c7be0c27ba63c0cfb1d06ab6fc7ca56394b6596cd6c377f4da52a0f1075b1868b4f98b4d502f991c7786b6421e093ea9ca22f77333718207ff3b951883", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c48c5a0cc79c0b9d3dcdad59fec6ff8e79877299cca2f5f2cc5c3b7b33892fb180a32fd35d6768a8e242f0e994f57fbd8d4fa6b86d8a2da9721e2bdf6425f43a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-19 12:26:26.189562) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 12:26:26.189897) ========= 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=31acecf42b6a79ec49471d4a000001bb-1482177935.444940-567354342 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004461 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 12:26:26.274067) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-19 12:26:26.274555) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-19 12:26:26.275062) ========= ========= Total master_lag: 0.17 =========