builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-079 starttime: 1502448624.21 results: success (0) revision: 64f5e3f40f0e7accb12cd90080e58eff0be46383 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-11 03:50:24.209481) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-11 03:50:24.210057) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-11 03:50:24.210578) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-11 03:50:24.227849) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 03:50:24.228239) ========= 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=1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-11 03:50:24-- 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% 46.2M=0s 2017-08-11 03:50:24 (46.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104348 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 03:50:24.347107) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 03:50:24.347483) ========= 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=1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020442 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 03:50:24.397739) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 03:50:24.398150) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 64f5e3f40f0e7accb12cd90080e58eff0be46383 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 64f5e3f40f0e7accb12cd90080e58eff0be46383 --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=1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-11 03:50:24,755 truncating revision to first 12 chars 2017-08-11 03:50:24,755 Setting DEBUG logging. 2017-08-11 03:50:24,755 attempt 1/10 2017-08-11 03:50:24,755 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/64f5e3f40f0e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-11 03:50:25,209 unpacking tar archive at: mozilla-central-64f5e3f40f0e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.834775 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 03:50:25.246088) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-11 03:50:25.248526) ========= script_repo_revision: 64f5e3f40f0e7accb12cd90080e58eff0be46383 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-11 03:50:25.249010) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-11 03:50:25.249288) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-11 03:50:25.258144) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-11 03:50:25.258494) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:50:25 INFO - MultiFileLogger online at 20170811 03:50:25 in /builds/slave/test 03:50:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:50:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:50:25 INFO - {'append_to_log': False, 03:50:25 INFO - 'base_work_dir': '/builds/slave/test', 03:50:25 INFO - 'blob_upload_branch': 'Firefox', 03:50:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:50:25 INFO - 'branch': 'Firefox', 03:50:25 INFO - 'buildbot_json_path': 'buildprops.json', 03:50:25 INFO - 'code_coverage': False, 03:50:25 INFO - 'config_files': ('talos/linux_config.py',), 03:50:25 INFO - 'default_actions': ('clobber', 03:50:25 INFO - 'read-buildbot-config', 03:50:25 INFO - 'download-and-extract', 03:50:25 INFO - 'populate-webroot', 03:50:25 INFO - 'create-virtualenv', 03:50:25 INFO - 'install', 03:50:25 INFO - 'setup-mitmproxy', 03:50:25 INFO - 'run-tests'), 03:50:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:50:25 INFO - 'disable_ccov_upload': False, 03:50:25 INFO - 'download_minidump_stackwalk': True, 03:50:25 INFO - 'download_symbols': 'ondemand', 03:50:25 INFO - 'enable_stylo': False, 03:50:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:50:25 INFO - 'tooltool.py': '/tools/tooltool.py', 03:50:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:50:25 INFO - '/tools/misc-python/virtualenv.py')}, 03:50:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:50:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:50:25 INFO - 'gecko_profile': False, 03:50:25 INFO - 'gecko_profile_interval': 0, 03:50:25 INFO - 'installer_path': 'installer.exe', 03:50:25 INFO - 'log_level': 'info', 03:50:25 INFO - 'log_name': 'talos', 03:50:25 INFO - 'log_to_console': True, 03:50:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:50:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:50:25 INFO - 'opt_config_files': (), 03:50:25 INFO - 'pip_index': False, 03:50:25 INFO - 'suite': 'dromaeojs-e10s', 03:50:25 INFO - 'system_bits': '32', 03:50:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:50:25 INFO - 'title': 'talos-linux64-ix-079', 03:50:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:50:25 INFO - 'use_talos_json': True, 03:50:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:50:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:50:25 INFO - 'work_dir': 'build'} 03:50:25 INFO - [mozharness: 2017-08-11 10:50:25.720692Z] Running clobber step. 03:50:25 INFO - Running pre-action listener: _resource_record_pre_action 03:50:25 INFO - Running main action method: clobber 03:50:25 INFO - rmtree: /builds/slave/test/build 03:50:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:50:26 INFO - Running post-action listener: _resource_record_post_action 03:50:26 INFO - [mozharness: 2017-08-11 10:50:26.737156Z] Finished clobber step (success) 03:50:26 INFO - [mozharness: 2017-08-11 10:50:26.737257Z] Running read-buildbot-config step. 03:50:26 INFO - Running pre-action listener: _resource_record_pre_action 03:50:26 INFO - Running main action method: read_buildbot_config 03:50:26 INFO - Using buildbot properties: 03:50:26 INFO - { 03:50:26 INFO - "project": "", 03:50:26 INFO - "product": "firefox", 03:50:26 INFO - "who": "archaeopteryx@coole-files.de", 03:50:26 INFO - "installer_path": "public/build/target.tar.bz2", 03:50:26 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 03:50:26 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:50:26 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 03:50:26 INFO - "stage_platform": "linux64-stylosequential", 03:50:26 INFO - "basedir": "/builds/slave/test", 03:50:26 INFO - "buildnumber": 180, 03:50:26 INFO - "platform": "ubuntu64_hw_styloseq", 03:50:26 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:50:26 INFO - "slavebuilddir": "test", 03:50:26 INFO - "taskId": "Ed6mOBf3SlWfMDWEjxPhsw", 03:50:26 INFO - "branch": "mozilla-central", 03:50:26 INFO - "script_repo_revision": "production", 03:50:26 INFO - "revision": "64f5e3f40f0e7accb12cd90080e58eff0be46383", 03:50:26 INFO - "slavename": "talos-linux64-ix-079", 03:50:26 INFO - "repo_path": "mozilla-central" 03:50:26 INFO - } 03:50:26 INFO - Finding installer, test and symbols from parent task. 03:50:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ed6mOBf3SlWfMDWEjxPhsw'}, attempt #1 03:50:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ed6mOBf3SlWfMDWEjxPhsw'}, attempt #1 03:50:27 INFO - Task dependencies: DccvNO8hRT2EKCynlXqtsg 03:50:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg'}, attempt #1 03:50:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.tar.bz2 03:50:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.test_packages.json 03:50:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.crashreporter-symbols.zip 03:50:28 INFO - Running post-action listener: _resource_record_post_action 03:50:28 INFO - [mozharness: 2017-08-11 10:50:28.088479Z] Finished read-buildbot-config step (success) 03:50:28 INFO - [mozharness: 2017-08-11 10:50:28.088700Z] Running download-and-extract step. 03:50:28 INFO - Running pre-action listener: _resource_record_pre_action 03:50:28 INFO - Running main action method: download_and_extract 03:50:28 INFO - mkdir: /builds/slave/test/build/tests 03:50:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:50:28 INFO - trying https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.test_packages.json 03:50:28 INFO - Downloading https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:50:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:50:28 INFO - Downloaded 989 bytes. 03:50:28 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:50:28 INFO - Using the following test package requirements: 03:50:28 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:50:28 INFO - u'common': [u'target.common.tests.zip'], 03:50:28 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:50:28 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:50:28 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:50:28 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:50:28 INFO - u'mozbase': [u'target.common.tests.zip'], 03:50:28 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:50:28 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:50:28 INFO - u'web-platform': [u'target.common.tests.zip', 03:50:28 INFO - u'target.web-platform.tests.tar.gz'], 03:50:28 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:50:28 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:50:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.common.tests.zip 03:50:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:50:28 INFO - Fetch https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.common.tests.zip into memory 03:50:29 INFO - Content-Length response header: 54036596 03:50:29 INFO - Bytes received: 54036596 03:50:40 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:50:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.common.tests.zip 03:50:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:50:40 INFO - Fetch https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.common.tests.zip into memory 03:50:41 INFO - Content-Length response header: 54036596 03:50:41 INFO - Bytes received: 54036596 03:50:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.talos.tests.zip 03:50:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:50:53 INFO - Fetch https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.talos.tests.zip into memory 03:50:54 INFO - Content-Length response header: 14496050 03:50:54 INFO - Bytes received: 14496050 03:50:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:50:54 INFO - trying https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.tar.bz2 03:50:54 INFO - Downloading https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:50:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:50:55 INFO - Downloaded 61791336 bytes. 03:50:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.tar.bz2 03:50:55 INFO - mkdir: /builds/slave/test/properties 03:50:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:50:55 INFO - Writing to file /builds/slave/test/properties/build_url 03:50:55 INFO - Contents: 03:50:55 INFO - build_url:https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.tar.bz2 03:50:55 INFO - Running post-action listener: _resource_record_post_action 03:50:55 INFO - Running post-action listener: set_extra_try_arguments 03:50:55 INFO - [mozharness: 2017-08-11 10:50:55.674710Z] Finished download-and-extract step (success) 03:50:55 INFO - [mozharness: 2017-08-11 10:50:55.674808Z] Running populate-webroot step. 03:50:55 INFO - Running pre-action listener: _resource_record_pre_action 03:50:55 INFO - Running main action method: populate_webroot 03:50:55 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:50:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:50:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:50:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:50:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:50:55 INFO - 'tests': ['damp', 'tps']}, 03:50:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:50:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:50:55 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:50:55 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:50:55 INFO - 'other-e10s': {'tests': ['a11yr', 03:50:55 INFO - 'ts_paint', 03:50:55 INFO - 'tpaint', 03:50:55 INFO - 'sessionrestore', 03:50:55 INFO - 'sessionrestore_many_windows', 03:50:55 INFO - 'sessionrestore_no_auto_restore', 03:50:55 INFO - 'tabpaint']}, 03:50:55 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:50:55 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:50:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:50:55 INFO - 'tsvgr_opacity', 03:50:55 INFO - 'tart', 03:50:55 INFO - 'tscrollx', 03:50:55 INFO - 'tsvg_static']}, 03:50:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:50:55 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:50:55 INFO - 'talos_options': ['--mitmproxy', 03:50:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:50:55 INFO - '--firstNonBlankPaint'], 03:50:55 INFO - 'tests': ['tp6_google', 03:50:55 INFO - 'tp6_youtube', 03:50:55 INFO - 'tp6_amazon', 03:50:55 INFO - 'tp6_facebook']}, 03:50:55 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:50:55 INFO - 'talos_options': ['--stylo', 03:50:55 INFO - '--mitmproxy', 03:50:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:50:55 INFO - '--firstNonBlankPaint'], 03:50:55 INFO - 'tests': ['tp6_google', 03:50:55 INFO - 'tp6_youtube', 03:50:55 INFO - 'tp6_amazon', 03:50:55 INFO - 'tp6_facebook']}, 03:50:55 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:50:55 INFO - 'talos_options': ['--stylo', 03:50:55 INFO - '--stylo-threads=1', 03:50:55 INFO - '--mitmproxy', 03:50:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:50:55 INFO - '--firstNonBlankPaint'], 03:50:55 INFO - 'tests': ['tp6_google', 03:50:55 INFO - 'tp6_youtube', 03:50:55 INFO - 'tp6_amazon', 03:50:55 INFO - 'tp6_facebook']}, 03:50:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:50:55 INFO - 'talos_options': ['--xperf_path', 03:50:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:50:55 INFO - 'tests': ['tp5n']}}} 03:50:55 INFO - Running post-action listener: _resource_record_post_action 03:50:55 INFO - [mozharness: 2017-08-11 10:50:55.683158Z] Finished populate-webroot step (success) 03:50:55 INFO - [mozharness: 2017-08-11 10:50:55.683248Z] Running create-virtualenv step. 03:50:55 INFO - Running pre-action listener: _resource_record_pre_action 03:50:55 INFO - Running main action method: create_virtualenv 03:50:55 INFO - Creating virtualenv /builds/slave/test/build/venv 03:50:55 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 03:50:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:50:55 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:50:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:50:55 INFO - Using real prefix '/usr' 03:50:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:50:56 INFO - Installing distribute.............................................................................................................................................................................................done. 03:50:58 INFO - Installing pip.................done. 03:50:58 INFO - Return code: 0 03:50:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:50:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:50:58 INFO - Reading from file tmpfile_stdout 03:50:58 INFO - Output received: 03:50:58 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) 03:50:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:50:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:50:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:50:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50: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/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 0x10271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1394b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1397f00>, '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 0x1392ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1393050>, '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': '1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481', '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 03:50:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 03:50:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:50:58 INFO - Using env: {'DISPLAY': ':0', 03:50:58 INFO - 'HOME': '/home/cltbld', 03:50:58 INFO - 'LANG': 'en_US.UTF-8', 03:50:58 INFO - 'LANGUAGE': 'en_US:en', 03:50:58 INFO - 'LOGNAME': 'cltbld', 03:50:58 INFO - 'MAIL': '/var/mail/cltbld', 03:50:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:58 INFO - 'MOZ_NO_REMOTE': '1', 03:50:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:50:58 INFO - 'NO_EM_RESTART': '1', 03:50:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:50:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:58 INFO - 'PWD': '/builds/slave/test', 03:50:58 INFO - 'SHELL': '/bin/bash', 03:50:58 INFO - 'SHLVL': '1', 03:50:58 INFO - 'TERM': 'linux', 03:50:58 INFO - 'TMOUT': '86400', 03:50:58 INFO - 'USER': 'cltbld', 03:50:58 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481', 03:50:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:58 INFO - '_': '/tools/buildbot/bin/python'} 03:50:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:58 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 03:50:58 INFO - Cleaning up... 03:50:58 INFO - Return code: 0 03:50:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:50:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:50:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:50:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50: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/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 0x10271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1394b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1397f00>, '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 0x1392ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1393050>, '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': '1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481', '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 03:50:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 03:50:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:50:58 INFO - Using env: {'DISPLAY': ':0', 03:50:58 INFO - 'HOME': '/home/cltbld', 03:50:58 INFO - 'LANG': 'en_US.UTF-8', 03:50:58 INFO - 'LANGUAGE': 'en_US:en', 03:50:58 INFO - 'LOGNAME': 'cltbld', 03:50:58 INFO - 'MAIL': '/var/mail/cltbld', 03:50:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:58 INFO - 'MOZ_NO_REMOTE': '1', 03:50:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:50:58 INFO - 'NO_EM_RESTART': '1', 03:50:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:50:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:58 INFO - 'PWD': '/builds/slave/test', 03:50:58 INFO - 'SHELL': '/bin/bash', 03:50:58 INFO - 'SHLVL': '1', 03:50:58 INFO - 'TERM': 'linux', 03:50:58 INFO - 'TMOUT': '86400', 03:50:58 INFO - 'USER': 'cltbld', 03:50:58 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481', 03:50:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:58 INFO - '_': '/tools/buildbot/bin/python'} 03:50:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:58 INFO - Downloading/unpacking psutil>=3.1.1 03:50: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 03:50: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 03:50: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 03:50: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 03:51:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:51:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:51:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:51:00 INFO - Installing collected packages: psutil 03:51:00 INFO - Running setup.py install for psutil 03:51:00 INFO - building 'psutil._psutil_linux' extension 03:51:00 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 03:51:00 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 03:51:00 INFO - building 'psutil._psutil_posix' extension 03:51:00 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 03:51:00 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 03:51:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:51:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:51:00 INFO - Successfully installed psutil 03:51:00 INFO - Cleaning up... 03:51:00 INFO - Return code: 0 03:51:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:51:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:51:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:51:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51: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/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 0x10271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1394b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1397f00>, '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 0x1392ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1393050>, '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': '1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481', '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 03:51:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 03:51:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:51:00 INFO - Using env: {'DISPLAY': ':0', 03:51:00 INFO - 'HOME': '/home/cltbld', 03:51:00 INFO - 'LANG': 'en_US.UTF-8', 03:51:00 INFO - 'LANGUAGE': 'en_US:en', 03:51:00 INFO - 'LOGNAME': 'cltbld', 03:51:00 INFO - 'MAIL': '/var/mail/cltbld', 03:51:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:00 INFO - 'MOZ_NO_REMOTE': '1', 03:51:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:51:00 INFO - 'NO_EM_RESTART': '1', 03:51:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:51:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:00 INFO - 'PWD': '/builds/slave/test', 03:51:00 INFO - 'SHELL': '/bin/bash', 03:51:00 INFO - 'SHLVL': '1', 03:51:00 INFO - 'TERM': 'linux', 03:51:00 INFO - 'TMOUT': '86400', 03:51:00 INFO - 'USER': 'cltbld', 03:51:00 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481', 03:51:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:00 INFO - '_': '/tools/buildbot/bin/python'} 03:51:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:51:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:51:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:51:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:51:02 INFO - Installing collected packages: mozsystemmonitor 03:51:02 INFO - Running setup.py install for mozsystemmonitor 03:51:02 INFO - Successfully installed mozsystemmonitor 03:51:02 INFO - Cleaning up... 03:51:02 INFO - Return code: 0 03:51:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:51:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:51:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:02 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/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 0x10271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1394b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1397f00>, '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 0x1392ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1393050>, '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': '1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481', '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 03:51:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 03:51:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:51:02 INFO - Using env: {'DISPLAY': ':0', 03:51:02 INFO - 'HOME': '/home/cltbld', 03:51:02 INFO - 'LANG': 'en_US.UTF-8', 03:51:02 INFO - 'LANGUAGE': 'en_US:en', 03:51:02 INFO - 'LOGNAME': 'cltbld', 03:51:02 INFO - 'MAIL': '/var/mail/cltbld', 03:51:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:02 INFO - 'MOZ_NO_REMOTE': '1', 03:51:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:51:02 INFO - 'NO_EM_RESTART': '1', 03:51:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:51:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:02 INFO - 'PWD': '/builds/slave/test', 03:51:02 INFO - 'SHELL': '/bin/bash', 03:51:02 INFO - 'SHLVL': '1', 03:51:02 INFO - 'TERM': 'linux', 03:51:02 INFO - 'TMOUT': '86400', 03:51:02 INFO - 'USER': 'cltbld', 03:51:02 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481', 03:51:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:02 INFO - '_': '/tools/buildbot/bin/python'} 03:51:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:02 INFO - Downloading/unpacking jsonschema==2.5.1 03:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:51:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:51: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 03:51: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 03:51: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 03:51: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 03:51:03 INFO - Downloading functools32-3.2.3-2.tar.gz 03:51:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:51:03 INFO - warning: no files found matching '*.txt' 03:51:03 INFO - no previously-included directories found matching 'build' 03:51:03 INFO - no previously-included directories found matching 'dist' 03:51:03 INFO - no previously-included directories found matching '.git*' 03:51:03 INFO - Installing collected packages: jsonschema, functools32 03:51:03 INFO - Running setup.py install for functools32 03:51:04 INFO - warning: no files found matching '*.txt' 03:51:04 INFO - no previously-included directories found matching 'build' 03:51:04 INFO - no previously-included directories found matching 'dist' 03:51:04 INFO - no previously-included directories found matching '.git*' 03:51:04 INFO - Successfully installed jsonschema functools32 03:51:04 INFO - Cleaning up... 03:51:04 INFO - Return code: 0 03:51:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:51:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:51:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:04 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/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 0x10271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1394b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1397f00>, '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 0x1392ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1393050>, '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': '1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481', '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 03:51:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 03:51:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:51:04 INFO - Using env: {'DISPLAY': ':0', 03:51:04 INFO - 'HOME': '/home/cltbld', 03:51:04 INFO - 'LANG': 'en_US.UTF-8', 03:51:04 INFO - 'LANGUAGE': 'en_US:en', 03:51:04 INFO - 'LOGNAME': 'cltbld', 03:51:04 INFO - 'MAIL': '/var/mail/cltbld', 03:51:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:04 INFO - 'MOZ_NO_REMOTE': '1', 03:51:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:51:04 INFO - 'NO_EM_RESTART': '1', 03:51:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:51:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:04 INFO - 'PWD': '/builds/slave/test', 03:51:04 INFO - 'SHELL': '/bin/bash', 03:51:04 INFO - 'SHLVL': '1', 03:51:04 INFO - 'TERM': 'linux', 03:51:04 INFO - 'TMOUT': '86400', 03:51:04 INFO - 'USER': 'cltbld', 03:51:04 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481', 03:51:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:04 INFO - '_': '/tools/buildbot/bin/python'} 03:51:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:51:04 INFO - Cleaning up... 03:51:04 INFO - Return code: 0 03:51:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:51:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:51:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:04 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/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 0x10271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1394b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1397f00>, '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 0x1392ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1393050>, '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': '1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481', '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 03:51:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 03:51:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:51:04 INFO - Using env: {'DISPLAY': ':0', 03:51:04 INFO - 'HOME': '/home/cltbld', 03:51:04 INFO - 'LANG': 'en_US.UTF-8', 03:51:04 INFO - 'LANGUAGE': 'en_US:en', 03:51:04 INFO - 'LOGNAME': 'cltbld', 03:51:04 INFO - 'MAIL': '/var/mail/cltbld', 03:51:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:04 INFO - 'MOZ_NO_REMOTE': '1', 03:51:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:51:04 INFO - 'NO_EM_RESTART': '1', 03:51:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:51:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:04 INFO - 'PWD': '/builds/slave/test', 03:51:04 INFO - 'SHELL': '/bin/bash', 03:51:04 INFO - 'SHLVL': '1', 03:51:04 INFO - 'TERM': 'linux', 03:51:04 INFO - 'TMOUT': '86400', 03:51:04 INFO - 'USER': 'cltbld', 03:51:04 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481', 03:51:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:04 INFO - '_': '/tools/buildbot/bin/python'} 03:51:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:04 INFO - Downloading/unpacking blobuploader==1.2.4 03:51: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 03:51: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 03:51: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 03:51: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 03:51:05 INFO - Downloading blobuploader-1.2.4.tar.gz 03:51:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:51:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:51:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:51: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 03:51: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 03:51: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 03:51: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 03:51:06 INFO - Downloading docopt-0.6.1.tar.gz 03:51:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:51:06 INFO - Installing collected packages: blobuploader, requests, docopt 03:51:06 INFO - Running setup.py install for blobuploader 03:51:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:51:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:51:06 INFO - Running setup.py install for requests 03:51:06 INFO - Running setup.py install for docopt 03:51:06 INFO - Successfully installed blobuploader requests docopt 03:51:06 INFO - Cleaning up... 03:51:06 INFO - Return code: 0 03:51:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:51:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:51:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:51:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:06 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/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 0x10271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1394b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1397f00>, '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 0x1392ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1393050>, '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': '1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481', '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 03:51:06 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:51:06 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:51:06 INFO - Using env: {'DISPLAY': ':0', 03:51:06 INFO - 'HOME': '/home/cltbld', 03:51:06 INFO - 'LANG': 'en_US.UTF-8', 03:51:06 INFO - 'LANGUAGE': 'en_US:en', 03:51:06 INFO - 'LOGNAME': 'cltbld', 03:51:06 INFO - 'MAIL': '/var/mail/cltbld', 03:51:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:06 INFO - 'MOZ_NO_REMOTE': '1', 03:51:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:51:06 INFO - 'NO_EM_RESTART': '1', 03:51:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:51:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:06 INFO - 'PWD': '/builds/slave/test', 03:51:06 INFO - 'SHELL': '/bin/bash', 03:51:06 INFO - 'SHLVL': '1', 03:51:06 INFO - 'TERM': 'linux', 03:51:06 INFO - 'TMOUT': '86400', 03:51:06 INFO - 'USER': 'cltbld', 03:51:06 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481', 03:51:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:06 INFO - '_': '/tools/buildbot/bin/python'} 03:51:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:51:07 INFO - Running setup.py (path:/tmp/pip-kI21gv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:51:07 INFO - Running setup.py (path:/tmp/pip-vR07VK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:51:07 INFO - Running setup.py (path:/tmp/pip-oNeHoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:51:07 INFO - Running setup.py (path:/tmp/pip-KFAVBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:51:07 INFO - Running setup.py (path:/tmp/pip-XT1qdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:51:07 INFO - Running setup.py (path:/tmp/pip-dpIk6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:51:07 INFO - Running setup.py (path:/tmp/pip-4mPfAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:51:07 INFO - Running setup.py (path:/tmp/pip-FEZ4bB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:51:07 INFO - Running setup.py (path:/tmp/pip-1riBvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:51:07 INFO - Running setup.py (path:/tmp/pip-4EhCrn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:51:07 INFO - Running setup.py (path:/tmp/pip-kiB8P3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:51:07 INFO - Running setup.py (path:/tmp/pip-3dxL5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:51:07 INFO - Running setup.py (path:/tmp/pip-DmT3Z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:51:08 INFO - Running setup.py (path:/tmp/pip-6YQ1ed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:51:08 INFO - Running setup.py (path:/tmp/pip-oxbh4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:51:08 INFO - Running setup.py (path:/tmp/pip-PWA16t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:51:08 INFO - Running setup.py (path:/tmp/pip-lj4OFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:51:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:51:08 INFO - Running setup.py install for manifestparser 03:51:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:51:08 INFO - Running setup.py install for mozcrash 03:51:08 INFO - Running setup.py install for mozdebug 03:51:08 INFO - Running setup.py install for mozdevice 03:51:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:51:08 INFO - Running setup.py install for mozfile 03:51:08 INFO - Running setup.py install for mozhttpd 03:51:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:51:08 INFO - Running setup.py install for mozinfo 03:51:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:51:08 INFO - Running setup.py install for mozInstall 03:51:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:51:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:51:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:51:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:51:09 INFO - Running setup.py install for mozleak 03:51:09 INFO - Running setup.py install for mozlog 03:51:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:51:09 INFO - Running setup.py install for moznetwork 03:51:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:51:09 INFO - Running setup.py install for mozprocess 03:51:09 INFO - Running setup.py install for mozprofile 03:51:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:51:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:51:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:51:09 INFO - Running setup.py install for mozrunner 03:51:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:51:09 INFO - Running setup.py install for mozscreenshot 03:51:09 INFO - Running setup.py install for moztest 03:51:09 INFO - Running setup.py install for mozversion 03:51:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:51:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:51:10 INFO - Cleaning up... 03:51:10 INFO - Return code: 0 03:51:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:51:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:51:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:51:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51: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/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 0x10271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1394b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1397f00>, '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 0x1392ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1393050>, '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': '1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481', '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 03:51: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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:51: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/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:51:10 INFO - Using env: {'DISPLAY': ':0', 03:51:10 INFO - 'HOME': '/home/cltbld', 03:51:10 INFO - 'LANG': 'en_US.UTF-8', 03:51:10 INFO - 'LANGUAGE': 'en_US:en', 03:51:10 INFO - 'LOGNAME': 'cltbld', 03:51:10 INFO - 'MAIL': '/var/mail/cltbld', 03:51:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:10 INFO - 'MOZ_NO_REMOTE': '1', 03:51:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:51:10 INFO - 'NO_EM_RESTART': '1', 03:51:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:51:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:10 INFO - 'PWD': '/builds/slave/test', 03:51:10 INFO - 'SHELL': '/bin/bash', 03:51:10 INFO - 'SHLVL': '1', 03:51:10 INFO - 'TERM': 'linux', 03:51:10 INFO - 'TMOUT': '86400', 03:51:10 INFO - 'USER': 'cltbld', 03:51:10 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481', 03:51:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:10 INFO - '_': '/tools/buildbot/bin/python'} 03:51:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:51:10 INFO - Running setup.py (path:/tmp/pip-2qEE8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:51: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)) 03:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:51:10 INFO - Running setup.py (path:/tmp/pip-LLMmVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:51:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:51:10 INFO - Running setup.py (path:/tmp/pip-FtnBnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:51:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:51:10 INFO - Running setup.py (path:/tmp/pip-IgjQqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:51:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 03:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:51:10 INFO - Running setup.py (path:/tmp/pip-vYS2Fl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:51:10 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)) 03:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:51:10 INFO - Running setup.py (path:/tmp/pip-tZhGVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:51:10 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)) 03:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:51:10 INFO - Running setup.py (path:/tmp/pip-YIx3W7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:51:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 03:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:51:10 INFO - Running setup.py (path:/tmp/pip-cD3zGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:51:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 03:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:51:10 INFO - Running setup.py (path:/tmp/pip-DN2Ktv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:51:10 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)) 03:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:51:10 INFO - Running setup.py (path:/tmp/pip-exFpc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:51:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 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)) 03:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:51:10 INFO - Running setup.py (path:/tmp/pip-9T3FrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:51: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)) 03:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:51:11 INFO - Running setup.py (path:/tmp/pip-SkrLDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:51:11 INFO - Running setup.py (path:/tmp/pip-M1fte0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:51: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)) 03:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:51:11 INFO - Running setup.py (path:/tmp/pip-h1Cm_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:51: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)) 03:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:51:11 INFO - Running setup.py (path:/tmp/pip-_mqaKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:51:11 INFO - Running setup.py (path:/tmp/pip-eiEhB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:51:11 INFO - Running setup.py (path:/tmp/pip-sUPsHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:51:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:12 INFO - Downloading blessings-1.6.tar.gz 03:51:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:51:12 INFO - Installing collected packages: blessings 03:51:12 INFO - Running setup.py install for blessings 03:51:12 INFO - Successfully installed blessings 03:51:12 INFO - Cleaning up... 03:51:12 INFO - Return code: 0 03:51:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:51:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:51:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:51:13 INFO - Reading from file tmpfile_stdout 03:51:13 INFO - Current package versions: 03:51:13 INFO - argparse == 1.2.1 03:51:13 INFO - blessings == 1.6 03:51:13 INFO - blobuploader == 1.2.4 03:51:13 INFO - docopt == 0.6.1 03:51:13 INFO - functools32 == 3.2.3-2 03:51:13 INFO - jsonschema == 2.5.1 03:51:13 INFO - manifestparser == 1.1 03:51:13 INFO - mozInstall == 1.13 03:51:13 INFO - mozcrash == 1.0 03:51:13 INFO - mozdebug == 0.1 03:51:13 INFO - mozdevice == 0.51 03:51:13 INFO - mozfile == 1.2 03:51:13 INFO - mozhttpd == 0.7 03:51:13 INFO - mozinfo == 0.10 03:51:13 INFO - mozleak == 0.1 03:51:13 INFO - mozlog == 3.5 03:51:13 INFO - moznetwork == 0.27 03:51:13 INFO - mozprocess == 0.25 03:51:13 INFO - mozprofile == 0.28 03:51:13 INFO - mozrunner == 6.13 03:51:13 INFO - mozscreenshot == 0.1 03:51:13 INFO - mozsystemmonitor == 0.3 03:51:13 INFO - moztest == 0.8 03:51:13 INFO - mozversion == 1.4 03:51:13 INFO - psutil == 3.1.1 03:51:13 INFO - requests == 1.2.3 03:51:13 INFO - wsgiref == 0.1.2 03:51:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:51:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:51:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:13 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/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 0x10271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1394b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1397f00>, '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 0x1392ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1393050>, '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': '1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481', '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 03:51:13 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:51:13 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:51:13 INFO - Using env: {'DISPLAY': ':0', 03:51:13 INFO - 'HOME': '/home/cltbld', 03:51:13 INFO - 'LANG': 'en_US.UTF-8', 03:51:13 INFO - 'LANGUAGE': 'en_US:en', 03:51:13 INFO - 'LOGNAME': 'cltbld', 03:51:13 INFO - 'MAIL': '/var/mail/cltbld', 03:51:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:13 INFO - 'MOZ_NO_REMOTE': '1', 03:51:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:51:13 INFO - 'NO_EM_RESTART': '1', 03:51:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:51:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:13 INFO - 'PWD': '/builds/slave/test', 03:51:13 INFO - 'SHELL': '/bin/bash', 03:51:13 INFO - 'SHLVL': '1', 03:51:13 INFO - 'TERM': 'linux', 03:51:13 INFO - 'TMOUT': '86400', 03:51:13 INFO - 'USER': 'cltbld', 03:51:13 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481', 03:51:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:13 INFO - '_': '/tools/buildbot/bin/python'} 03:51:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:13 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)) 03:51:13 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)) 03:51:13 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)) 03:51:13 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)) 03:51:13 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)) 03:51:13 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)) 03:51:13 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)) 03:51:13 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)) 03:51:13 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)) 03:51:13 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:51:13 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 03:51:13 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 03:51:13 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 03:51:13 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 03:51:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:51: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)) 03:51: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)) 03:51:14 INFO - Installing collected packages: simplejson 03:51:14 INFO - Running setup.py install for simplejson 03:51:14 INFO - building 'simplejson._speedups' extension 03:51:14 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 03:51:15 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 03:51:15 INFO - Successfully installed simplejson 03:51:15 INFO - Cleaning up... 03:51:15 INFO - Return code: 0 03:51:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:51:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:51:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:51:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:15 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/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 0x10271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1394b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1397f00>, '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 0x1392ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1393050>, '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': '1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481', '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 03:51:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:51:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:51:15 INFO - Using env: {'DISPLAY': ':0', 03:51:15 INFO - 'HOME': '/home/cltbld', 03:51:15 INFO - 'LANG': 'en_US.UTF-8', 03:51:15 INFO - 'LANGUAGE': 'en_US:en', 03:51:15 INFO - 'LOGNAME': 'cltbld', 03:51:15 INFO - 'MAIL': '/var/mail/cltbld', 03:51:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:15 INFO - 'MOZ_NO_REMOTE': '1', 03:51:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:51:15 INFO - 'NO_EM_RESTART': '1', 03:51:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:51:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:15 INFO - 'PWD': '/builds/slave/test', 03:51:15 INFO - 'SHELL': '/bin/bash', 03:51:15 INFO - 'SHLVL': '1', 03:51:15 INFO - 'TERM': 'linux', 03:51:15 INFO - 'TMOUT': '86400', 03:51:15 INFO - 'USER': 'cltbld', 03:51:15 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481', 03:51:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:15 INFO - '_': '/tools/buildbot/bin/python'} 03:51:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:51:15 INFO - Cleaning up... 03:51:15 INFO - Return code: 0 03:51:15 INFO - Running post-action listener: _resource_record_post_action 03:51:15 INFO - Running post-action listener: _start_resource_monitoring 03:51:15 INFO - Starting resource monitoring. 03:51:15 INFO - [mozharness: 2017-08-11 10:51:15.770328Z] Finished create-virtualenv step (success) 03:51:15 INFO - [mozharness: 2017-08-11 10:51:15.770837Z] Running install step. 03:51:15 INFO - Running pre-action listener: _resource_record_pre_action 03:51:15 INFO - Running main action method: install 03:51:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:51:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:51:15 INFO - Reading from file tmpfile_stdout 03:51:15 INFO - Detecting whether we're running mozinstall >=1.0... 03:51:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:51:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:51:16 INFO - Reading from file tmpfile_stdout 03:51:16 INFO - Output received: 03:51:16 INFO - Usage: mozinstall [options] installer 03:51:16 INFO - Options: 03:51:16 INFO - -h, --help show this help message and exit 03:51:16 INFO - -d DEST, --destination=DEST 03:51:16 INFO - Directory to install application into. [default: 03:51:16 INFO - "/builds/slave/test"] 03:51:16 INFO - --app=APP Application being installed. [default: firefox] 03:51:16 INFO - mkdir: /builds/slave/test/build/application 03:51:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:51:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:51:34 INFO - Reading from file tmpfile_stdout 03:51:34 INFO - Output received: 03:51:34 INFO - /builds/slave/test/build/application/firefox/firefox 03:51:34 INFO - Running post-action listener: _resource_record_post_action 03:51:34 INFO - [mozharness: 2017-08-11 10:51:34.079815Z] Finished install step (success) 03:51:34 INFO - [mozharness: 2017-08-11 10:51:34.080078Z] Running setup-mitmproxy step. 03:51:34 INFO - Running pre-action listener: _resource_record_pre_action 03:51:34 INFO - Running main action method: setup_mitmproxy 03:51:34 INFO - Skipping: mitmproxy is not required 03:51:34 INFO - Running post-action listener: _resource_record_post_action 03:51:34 INFO - [mozharness: 2017-08-11 10:51:34.081217Z] Finished setup-mitmproxy step (success) 03:51:34 INFO - [mozharness: 2017-08-11 10:51:34.081437Z] Running run-tests step. 03:51:34 INFO - Running pre-action listener: _resource_record_pre_action 03:51:34 INFO - Running pre-action listener: _set_gcov_prefix 03:51:34 INFO - Running main action method: run_tests 03:51:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:51:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:51:34 INFO - Python 2.7.3 03:51:34 INFO - Return code: 0 03:51:34 INFO - grabbing minidump binary from tooltool 03:51:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:51:34 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 0x1397f00>, '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 0x1392ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1393050>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:51:34 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 03:51:34 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 03:51:34 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 03:51:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:51:34 INFO - Return code: 0 03:51:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:51:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:51:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:51:34 INFO - ENV: RUST_BACKTRACE is now full 03:51:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:51:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:51:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-079', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:51:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-079 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:51:34 INFO - Using env: {'DISPLAY': ':0', 03:51:34 INFO - 'HOME': '/home/cltbld', 03:51:34 INFO - 'LANG': 'en_US.UTF-8', 03:51:34 INFO - 'LANGUAGE': 'en_US:en', 03:51:34 INFO - 'LOGNAME': 'cltbld', 03:51:34 INFO - 'MAIL': '/var/mail/cltbld', 03:51:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:51:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:51:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:34 INFO - 'MOZ_NO_REMOTE': '1', 03:51:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:51:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:51:34 INFO - 'NO_EM_RESTART': '1', 03:51:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:51:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:34 INFO - 'PWD': '/builds/slave/test', 03:51:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:51:34 INFO - 'RUST_BACKTRACE': 'full', 03:51:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:51:34 INFO - 'SHELL': '/bin/bash', 03:51:34 INFO - 'SHLVL': '1', 03:51:34 INFO - 'STYLO_FORCE_ENABLED': '1', 03:51:34 INFO - 'TERM': 'linux', 03:51:34 INFO - 'TMOUT': '86400', 03:51:34 INFO - 'USER': 'cltbld', 03:51:34 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481', 03:51:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:34 INFO - '_': '/tools/buildbot/bin/python'} 03:51:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-079', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:51:34 INFO - mozversion application_buildid: 20170811095810 03:51:34 INFO - mozversion application_changeset: 64f5e3f40f0e7accb12cd90080e58eff0be46383 03:51:34 INFO - mozversion application_display_name: Nightly 03:51:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:51:34 INFO - mozversion application_name: Firefox 03:51:34 INFO - mozversion application_remotingname: firefox 03:51:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:51:34 INFO - mozversion application_vendor: Mozilla 03:51:34 INFO - mozversion application_version: 57.0a1 03:51:34 INFO - mozversion platform_buildid: 20170811095810 03:51:34 INFO - mozversion platform_changeset: 64f5e3f40f0e7accb12cd90080e58eff0be46383 03:51:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:51:34 INFO - mozversion platform_version: 57.0a1 03:51:34 INFO - using testdate: 1502448694 03:51:34 INFO - actual date: 1502448694 03:51:34 INFO - starting webserver on 'localhost:58226' 03:51:34 INFO - SUITE-START | Running 2 tests 03:51:34 INFO - TEST-START | dromaeo_css 03:51:34 INFO - Initialising browser for dromaeo_css test... 03:51:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58226/getInfo.html -profile /tmp/tmpq0ePTP/profile 03:51:34 INFO - TEST-INFO | started process 8675 (/builds/slave/test/build/application/firefox/firefox http://localhost:58226/getInfo.html) 03:51:41 INFO - TEST-INFO | 8675: exit 0 03:51:41 INFO - Browser initialized. 03:51:41 INFO - Running cycle 1/1 for dromaeo_css test... 03:51:41 INFO - TEST-INFO | started process 8906 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpq0ePTP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:51:42 INFO - PID 8906 | 03:51:42 INFO - PID 8906 | (/builds/slave/test/build/application/firefox/firefox:8960): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:51:42 INFO - PID 8906 | 03:51:52 INFO - PID 8906 | RSS: Main: 168120320 03:51:52 INFO - PID 8906 | 03:54:52 INFO - PID 8906 | Cycle 1(1): loaded http://localhost:58226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58226/tests/dromaeo/cssquery-ext.html) 03:54:53 INFO - PID 8906 | RSS: Main: 175669248 03:54:53 INFO - PID 8906 | 03:57:55 INFO - PID 8906 | Cycle 1(1): loaded http://localhost:58226/tests/dromaeo/cssquery-ext.html (next: http://localhost:58226/tests/dromaeo/cssquery-jquery.html) 03:57:56 INFO - PID 8906 | RSS: Main: 172404736 03:57:56 INFO - PID 8906 | 04:00:58 INFO - PID 8906 | Cycle 1(1): loaded http://localhost:58226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58226/tests/dromaeo/cssquery-mootools.html) 04:00:58 INFO - PID 8906 | RSS: Main: 175054848 04:00:58 INFO - PID 8906 | 04:04:00 INFO - PID 8906 | Cycle 1(1): loaded http://localhost:58226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58226/tests/dromaeo/cssquery-prototype.html) 04:04:01 INFO - PID 8906 | RSS: Main: 173092864 04:04:01 INFO - PID 8906 | 04:07:05 INFO - PID 8906 | Cycle 1(1): loaded http://localhost:58226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58226/tests/dromaeo/cssquery-yui.html) 04:07:05 INFO - PID 8906 | RSS: Main: 176762880 04:07:05 INFO - PID 8906 | 04:10:07 INFO - PID 8906 | Cycle 1(1): loaded http://localhost:58226/tests/dromaeo/cssquery-yui.html (next: http://localhost:58226/tests/dromaeo/cssquery-dojo.html) 04:10:07 INFO - PID 8906 | RSS: Main: 175603712 04:10:07 INFO - PID 8906 | 04:10:07 INFO - PID 8906 | __start_tp_report 04:10:07 INFO - PID 8906 | _x_x_mozilla_page_load 04:10:07 INFO - PID 8906 | _x_x_mozilla_page_load_details 04:10:07 INFO - PID 8906 | |i|pagename|runs| 04:10:07 INFO - PID 8906 | |0;dojo.html;3140.1364624728203;3149.4016136933983;3177.714005739483;3193.8083714977097;3205.967940320596;6133.907991380132;6217.37826217378;6236.06459031145;6244.594101383407;6260.7808726694575;6191.783287584935;6241.282252540959;6291.559590828644;6345.048242763588;6357.014662727284;5761.884762304751;5790.971045144779;5799.82600521985;5825.766969321243;5833.125031245305;5750.3962083981205;5781.248437703092;5781.335146458148;5782.595218334718;5865.648061116325;5601.719914004283;5670.234518340054;5684.715764211794;5706.457886500776;5713.942860571383;5626.437356264381;5688.459596338341;5705.71471426427;5707.172459993278;5721.8569535761935;5708.4862362387585;5711.779423638621;5768.048272035109;5779.45095215958;5789.131630255453;6200.968995155041;6219.751209951642;6300.30696623371;6300.905486417708;6324.557280990332;6567.802965911031;6685.331466853276;6716.0933274007875;6755.831104222385;6820.147481564804;6761.222459417181;6765.255821859591;6781.22015968165;6788.59268443895;6810.080639113706;6190.783322583733;6201.255849298113;6259.906101408483;6279.089532017878;6302.275238347649;6994.4404447644065;7000.369966703021;7008.123984501937;7024;7053.470989675795;6426.678666066678;6527.15147030883;6740.932590674129;6751.156105486814;6758.83102922421;6690.632015239208;6719.395254427125;6719.563228390237;6721.227058888193;6722.327767223336;6578.671066446755;6605.900911486331;6649.368310010541;6652.83367915806;6681.966590167018;6670.132882725215;6723.697433615499;6748.628825414649;6778.830529236809;6782.728690852409;5345.946540534623;5426.810061647823;5440.401555828856;5505.366882808448;5510.9448905510435;4624.28323609841;4640.86077417678;4670.462896766848;4681.976590117096;4731.95268047322;5831.941680583224;5836.474717275466;5870.530357571384;5877.0596704527925;5926.259217597801;6804.795856124324;6818.420434263143;6887.449004079662;6893.620850853151;6896.793096207121;5833.562482813806;5863.003289440805;5904.5571582131515;5925.822225333247;5932.495737862244;5904.881902361929;5978.611390259619;6000.219971403777;6018.638881667114;6065.3934606539;6474.805755827333;6540.4767618590395;6551.148350714377;6557.983512555562;6569.704363303663;6663.666816659051;6773.458123350219;6774.762883299061;6778.932210678027;6786.287439818748;6413.294537600954;6517.608943463408;6530.967345163243;6534.085228068169;6550.737970481318;6675.933240667726;6684.665766711548;6741.797746067626;6753.257141714505;6767.661616919233;6732.192136943598;6769.695363708546;6803.625800581016;6825.761098361533;6859.279775623488;6421.775237866839;6496.935030649822;6549.901751473636;6551.705173267119;6639.402453779183;6565.868682626417;6586.934130658824;6590.340965903371;6597.109390232449;6621.900671490028;6498.675066246763;6541.672916354259;6590.47276217911;6597.439217666552;6603.240627327918;6426.742930282736;6494.1232933553365;6513.54405191632;6536.509761767791;6552.410283074546;6372.203474565679;6421.935780642134;6424.421802037839;6428.228612566522;6436.935630643634 04:10:07 INFO - PID 8906 | |1;ext.html;778.1829079466562;838.4717627894428;873.2969959182856;876.4609764994528;1179.5222934711435;30589.54115688264;41086;41856.79071604642;42101.7894910525;42103.57896421035;42528;42883.78558107205;42915.35626965598;42986.785066074626;43056.3541546877;7535.434842386816;7593.77218683439;7723.304902558769;7739.3421559167255;7746.767596972099;7338.156112047102;7653.923460765404;7654.387648988068;7674.386049116085;7678.270564296383;7644.350230230437;7685.923140768605;7686;7686.884696729554;7698.884516732225;7631.809204769869;7651.081870175587;7670.808229794244;7671.7314893979;7684.231576842327;7473.140588832245;7546.811329716801;7557.2820582044615;7559.055118110236;7559.924400756032;38502.80748596239;38735.418968715494;38767.61232387668;38800.417993730116;38940.22119557593;3196.4246435641576;3219.6297425795865;3232.385846689145;3243.6918492743152;3260.8043517388796;30715.232119196975;30730.84634576835;30783.2304192397;30795.846020769754;30827.229319266975;4901.583365413909;4922.237053256751;4948.406191257036;4951.133551628486;4972.5276098770555;191320;192841;192958;193399;194342;49830.750846246265;49894;50006.24990625143;50136.247956280684;50149.74925125351;52226.73886630542;52293.215601766;52644.473555264725;52670.47329526656;52745.47254527405;14679.045862019148;16466.259018344204;16888;16926.492205233862;16951.49145525636;12490.687732806753;13911.513097041556;14104;14154.079984800956;14164.291785410689;14527.055741376776;14572.927135364256;14613.634659133606;14632.926835365755;14691.11853288806;5608.9439105609235;5644.266245388073;5686.658800471985;5778.104393818965;5934.287885453773;1011.6813203840767;1022.5245260953543;1031.6750223679667;1035.9067683908484;1047.7014050995583;22794.886025569765;23007.30978070627;23155.53688926212;23287.7671223289;23338.64992025121;1125.6004118537965;1125.9155563332783;1128.6331942118845;1130.9990658267327;1133.9943300283446;12052.397380130958;12547.686307842378;12553.811692824615;12582.622521324374;12607;226475;227028;227076;227152;227348;52932.47067529352;53173.46826531763;53191;53366;53402.73298633482;1431.4918204037624;1437.3696333422204;1481.0743285446597;1482.1033274868548;1506.164078936201;23134.76865231326;23145;23147.76852231456;23213.651795222748;23379;37546;37599.624003760706;37621.247575048896;37635.24729505449;37692.05769855666;202684;203516;203681;203852;203923;34411.13972150716;35088;36782.44826327554;36906.44640330451;36954.07614809544;46723.532764671916;46807.29789053235;47123.52876471191;47220;47673.76163119162;33291.66708332886;33711.83144084264;33738.325233495685;33947.66052339445;34160.65839341674;33243.833780830944;33363.66636333606;33581.32837343288;33725.15687107842;33953.32093358169;36899.81550092233;37166.81416593008;37362.8131859339;37654;37865.242695146495;32986.505202421504;33432.665673342955;33553.66446335505;33733.662663373056;33789;24655.63016554789;25147.74852251454;25153.748462515872;25679.358016049748;26356.472870542864 04:10:07 INFO - PID 8906 | |2;jquery.html;563.8308902307164;567.4155619711696;567.7615401531358;570.133397236201;574.2305310883416;21988.560228795413;22656.546869062608;22656.546869062608;22682.43293917651;23693.407664808346;25628.871855640744;25960.610590841152;26200;26205.86897065512;26237.212883613476;6325.525585581088;6421.229452465687;6477.093206951031;6479.384458476437;6480.74077036918;6124.387561243884;6332.873342533147;6384.48924086072;6409.8718025639455;6409.967950160242;6380.872382552347;6386.1697979262635;6387.041943708435;6388.840278993016;6390.3609639036185;6343.682815859189;6354.5551811373225;6367.204099487564;6373.330800265951;6374.426301632852;5951.91072133914;5956.404359564052;5961.493273071794;5964.821055368302;5966;28369.716302836914;28694.569581456086;28888.133355999144;29043.419131617462;29204.561931571043;1889.7354370388157;1924.9230030798751;1930.6138772245606;1930.7200455933812;1931.7875033746418;18966.525836854053;19058.52353691155;20011.399658010283;20166.798332016784;20189.899050504653;2206.8896555172178;2247.741509726386;2398.787404654805;2418.129473389596;2466.3340897957632;140055;144852;149830;151376;151732.2413387926;14809.777853332209;14998.250087495582;15339.92330038365;15417.537473875802;15510.379584816706;23625.645615315785;23634.29097127089;23654.881725591607;23671.408214794767;23681.881590591936;10278.537465814057;10426.582936682447;10581.947090264499;10709.78580428387;10837.133029357632;9966.950165249127;10034;10039.899601003897;10057.245706572035;10194.694159175237;9367.672131475365;9433.386829856037;9451.952740236393;9500;9537.998510156473;2835.844028578448;2858;2859.771218302531;2880.4815133275793;2950.7786915981387;946.3801210207325;949.4161090929167;959.8656188133667;969.5297780576415;972.1687956796978;19489.122989465508;19522.316718914768;19804.90097549532;19811.50471238202;20036;1024.4519182237468;1030.4229631406436;1037.1339931157418;1039.7920415916697;1048.3500229857384;10619.415932123808;10635.787284254271;11024.007839294502;11071.225014249087;11197.272177308449;423136;428587;428819;437466;441250;114942;116018.41990789992;116470;116900.41549792196;118519.40740296243;1070.1920050361928;1088.4285749981166;1206.1737709668905;1212.9757404851853;1236.110000799423;21023.684644730034;21127.788722112582;22117.115315387204;22126.88936555307;22298.665520017537;56022.43977560172;56733;58582.707086464296;60804.391956079875;61371.38628613835;67769;68215.3178468209;68289.31710683028;68819.65590172017;69144;14518.419263229353;14718.411263549338;14740.189289588747;14768;14948.775768363701;22938.885305573367;23175;23307.184248551897;23434.414139646644;23474.41313967096;18286.908565457088;18305.26778928828;18916.527086822938;19131.713024304925;19149.234030638618;15992.960457570221;15995.200239988188;16437.671246574762;16647.334106636084;17155.228014739117;18720.157592908625;19338;19437.51406214856;19519.8048019518;19687.21251149995;14512.346944387316;14531.128132312095;14653.92673036628;14695.485658001919;14697.55907322782;18348.90825545864;18494.16776245099;18597.81402186015;18648.254069837054;18689.53276168107 04:10:07 INFO - PID 8906 | |3;mootools.html;1282.3780466473759;1312.5668529385305;1331.26780270851;1335.6193484856813;1381.3024422666556;13921.1647301162;14281.928590357034;14435.927820360883;14512.12927224366;14521.927390363047;14383.496577619786;14769.261536923137;14824;14826.55520334389;14830.406783728666;2755.9586606200874;2781.7913656475744;2783.9443211135867;2784.8050636455455;2801.80387372884;2648;2717.0490328385104;2764.1707487753692;2765.2395591212476;2766.7786577073784;2617.4765046990583;2655.9468810623775;2657.853818040007;2658.7341265873356;2666.8666566671686;2603.2450589329164;2619.9607005894736;2626.8817903194404;2627.2118364490575;2628.921132366032;2618.0967566189634;2648.3776312566533;2649.5893136563864;2652.840829550214;2661.334666333417;13935.303234838217;14165.504207352797;14166.716665666629;14184.078034927697;14218.07582507144;7657.157712651604;7950.960245198795;7960.761177164694;7976;7978.162292959214;5044.293798868125;5267.26258323835;5307.522322990911;5352.143657014898;5398.24424580559;1833.4957886581296;1840.4386662068064;1871.1579789094826;1901.077977181066;1903.8286554210188;23129.421764455856;23144.76855231443;23215.419614509603;23300.067997280257;23312.7668723314;6328.398802113792;6502.349765023459;6541.443977261891;6686.498512611476;6692.698828552727;15910.12494312824;15910.44313449047;15927.203639817963;15948;15955.760663590056;7403.333699967029;7672.65473053714;7673.232676732282;7673.923260767432;7677.884831727529;5906.911396329058;6139.877202455926;6252.218472690914;6267.467265282411;6288.842778930563;5169.121249387613;5247.422783493813;5352.946470535323;5357.866053348619;5358.303420555303;1494.9177795221149;1516.219147139224;1526.4886263101985;1530.9387624495118;1548.6051056980623;843.2495079379357;854.679495189304;871.2028493928111;878.2139984713646;892.8571428571528;7229.819254518679;7234.855302893912;7266.672999715025;7274.709011639581;7281.635918204069;708.1997343002374;708.7661071846278;709.1206903439697;710.9158533236855;729.9343059124704;3871.845126194977;4546.158960592301;5032.647714660012;5103.617228707788;5172.431032586413;20020;20678.379648610568;20787.272445464336;20800.791992080187;20839.47901302449;19278.421647350602;20436.591268174554;20719;20770.376888693365;20772.584548309253;10358.482075896174;11122.61070862516;11299.491522881326;11321.320720756781;11380.374079425712;8319.209675080858;8554.700585479728;8571.614277357408;8685;8727.825443491221;17418.9129054359;18047.18787654585;18144.092795359917;18166.72749908726;18266.452006439828;17814.643707125524;18086.457406277834;18222.72665910039;18591.256349745858;18641.161147748113;11336.75295717487;11553.768924621292;11562.826557601811;11923.642290731292;12062.577809776967;13473.932630336785;13658.04393692432;13680.315984200552;13685.452581896612;13692.52076177329;10725.08836748885;10882.891171088188;10967.616133435291;10985.285956412808;11221.775564488502;10324.587016519255;10407.895921040692;10506.947465262625;10627.25609207379;10809.56761729522;9720.611175552898;9946.055124763136;9952.10431061208;9952.751181220527;9963.053509916546;6849.212340580897;6865.450763938973;6903.378695917391;6937.618430986344;7074.398676112588;7059.258777828254;7136.892946605909;7164.928350716426;7219.963900180465;7297.416206703557 04:10:07 INFO - PID 8906 | |4;prototype.html;495.20025159367634;499.52485434671195;517.3998162131928;528.2446102074034;533.1868899927609;6204.534659900514;6285.9999999999945;6304;6304.842378940529;6320.715567799449;5978.970105149479;6049.969750151243;6132.264128304599;6158.137860699506;6181.969090154543;4934.087193869137;4942.382202224722;4994.375703037121;5012.674176178555;5055.191169412895;4818.927716084244;4976.427710813248;4977.303177555145;5010.624203184758;5014.2979982802435;4909.214525675892;4923.630727695419;4963.553280204781;4967.875803104916;4969.950300496985;4949.208126699711;4959.628027897904;4973.781423551217;4987.426445958725;5015.122353588125;4390.560943905616;4413.44831896013;4459.777011149429;4463.375127482155;4476.776161191928;6146.262448506208;6150.4783038954265;6150.846228844314;6173.783917562909;6184.443400093968;5735.254416925814;5750.683712395817;5769.769209231626;5800.7679692812235;5804.042333015047;6564.770233041869;6584.93415065848;6625.238097618787;6636.137302150738;6669.966650166767;4867.342908707315;4875.561199492027;4890.437599676012;4895.804167833282;4904.313396124509;6974.140602353062;6974.4420446364165;6987.685554150075;7006.404455621278;7043.8591228175665;4999.725015124131;5058.924116138261;5059.19052951526;5072.67027643202;5075.168247972757;5123.308353372247;5143.922841157386;5162.406323272797;5165.225216217522;5171.250168725589;5721.713914304268;5769.913451298234;5773.04744717117;5776.797812076557;5802.970985145047;6580.605163690098;6600.273969863311;6603.448189675422;6617.9669101655145;6718.899216511756;1659.585103724069;1687.1226961979946;1720.7849018872641;1752.509297396731;1753.3512600337956;1205.391277404905;1213.7633161533593;1244.3156264054733;1247.077162899466;1247.8315427417365;5696.291112666184;5699.74351154199;5709.600327977085;5724.599278050497;5736.942630573641;6469.77355792545;6705.932940670628;6761.377269455394;6778.864422711518;6804.863902721918;5786.971065144648;5807.12893066035;5833.4749872511675;5882.852928676817;5889.1755154278435;3615.7468977171347;3615.897151368805;3628.76413033152;3641.7632853864943;3649.59854416014;6478.611283323015;6550.246721627074;6565.441937435371;6616.503762217853;6642.767503137367;6957.234704182536;6983.533457973781;7032.718691252395;7056.400205982446;7111.502194846311;6561.687662467526;6565.21217453899;6601.382661248025;6616.569922954993;6623.900641490285;6803.489738269748;6808.659567021728;6817.7272909083085;6846.657667116724;6857.485688573477;6580.696756135506;6593.900866592694;6699.851069997094;6730.427913627396;6776.796696099125;6419.550631455753;6474.4820414365795;6531.183602049744;6532.5753826001155;6543.738250469928;4869.245266983623;4962.578180854668;4978.875528111681;4980.452150263541;5015.322931404214;6493;6739.393454589111;6805.489588280998;6842.1789385272095;6894.24163342042;6223.315435302204;6430.582012169194;6461.092508371158;6511;6575.50683698715;6485;6513.15329007245;6525.771597994048;6542.1214665547;6571.507136964842;6429.58207716497;6469.79745026215;6488.545801793829;6520.869582608227;6534.705938232696;6523.412892839668;6580.80257592273;6595.868082638416;6623.172103487064;6649.268580456049;6411.294757576569;6434.549581529435;6448.5486015980305;6462.447333303413;6463.385978332051 04:10:07 INFO - PID 8906 | |5;yui.html;870.677849195798;905.9592318345675;912.4570880326204;915.5605309451463;920.5443305563747;18230.088495575234;18784.15471303791;19816.801831981677;19819.207231710716;19834.20663173473;20379.796202037975;21342.35972920811;21605.783942160575;21642.675359869616;21802.89098554505;820.7004443378169;1062.5484169228087;1064.393295821382;1065.8081545321838;1066.2749330455288;1054.9947250263738;1069.4064794039305;1069.9411532365716;1071.036067539213;1071.1591400750413;1105.6793529876327;1127.216584473791;1130.8473356096904;1130.8586426696663;1131.1911982932202;1109.3454861631635;1119.0152665654255;1119.1326721790597;1119.4067144413514;1120.1990576737671;810.0158307656226;810.080558566026;814.0963530481162;815.502543448496;816.4244207833464;17735.645287094314;17744.37894673693;17808;20278.69581956272;20358.796412035837;20560.177592896267;20655.69016464754;20791.168353266003;21000.474988125417;21192.894035529724;16253.106079165642;16458;16460.6707865842;17054.488365349058;17905.641887162314;3669.082729317671;3686.6866316362875;3687.2625474904858;3690.4833323334487;3692.2061756722433;126973;128218;130586;131755.99999999904;133214.33392832972;16372.83627163713;16744.24650890713;16973.321067157176;17004.234809433605;17277.82722172787;31141.8442907784;31275.530867037014;31283.530747038814;31308.373832523223;31860.840695796374;7813.101493328227;7955.363570914313;8273.544955027532;8386.245237928615;8435.24082832548;3734.7012239020814;4291.249031419551;4305.461817272841;4323.697341186087;4324.827006919751;5306.389765176977;5365.865853353697;5381.031414345455;5411.83764487066;5418.647787893853;1032.8708911386077;1046.1735229168871;1053.1259054984498;1058.3596923861107;1059.4437920092014;701.1831216632668;713.3045280851129;713.8144082538578;715.2132654080469;732.5751064382646;12072.215306005082;12396.938015310046;12446.31545265001;12748.553800616934;12837.293948832717;698.8567343694531;705.3017512662427;705.544923524324;707.4729326651677;715.2418436457372;5695.288088988877;5717.542596592346;5731.570132240098;5869.413058694096;5900.793472228452;113402;114718;115241.4237928805;115967.42016290034;117672.41163794126;103326;105866;106013.46993265138;106312;107267;85983.57008214919;88784;90436;91152.44237788084;94296;12293.754124917272;13042.478300867859;13047.543335983195;13208.339583021003;13506.324683765575;39254.6074539251;40072.79963600163;40561.391579126925;41620.375694364004;42639;70650.64674676594;74214;75007;76142.61928690321;76490.23509764831;18245.81754182494;18407;18482.537936551693;18860;18974.620507590047;18758.812411875708;19028.143733531746;19573.119209635886;19877.204911803365;22315.10739570464;17086.48740537786;18641.254349825856;19103.52241193981;19191.32830350931;19371.903140484206;17594.38419655306;17715.468535943426;18904.810951890857;19480.220791168194;20560.28039018627;17937.192826322585;18057.548561286072;19579.902100489406;19723.506912327306;19905.701414479085;13367.465301387836;13693.863061369259;13823.79264311016;14287.857121429068;14387.424503019762;14530.564083077525;14744.189069601274;14749.926250368679;14806.185659788815;14982 04:10:07 INFO - PID 8906 | __end_tp_report 04:10:07 INFO - PID 8906 | __start_cc_report 04:10:07 INFO - PID 8906 | _x_x_mozilla_cycle_collect,397 04:10:07 INFO - PID 8906 | __end_cc_report 04:10:07 INFO - PID 8906 | __startTimestamp1502449807795__endTimestamp 04:10:07 INFO - PID 8906 | 04:10:07 INFO - PID 8906 | ------- Summary: start ------- 04:10:07 INFO - PID 8906 | Number of tests: 6 04:10:07 INFO - PID 8906 | 04:10:07 INFO - PID 8906 | [#0] dojo.html Cycles:165 Average:6206.35 Median:6428.23 stddev:736.63 (11.5%) stddev-sans-first:698.52 04:10:07 INFO - PID 8906 | Values: 3140.1 3149.4 3177.7 3193.8 3206.0 6133.9 6217.4 6236.1 6244.6 6260.8 6191.8 6241.3 6291.6 6345.0 6357.0 5761.9 5791.0 5799.8 5825.8 5833.1 5750.4 5781.2 5781.3 5782.6 5865.6 5601.7 5670.2 5684.7 5706.5 5713.9 5626.4 5688.5 5705.7 5707.2 5721.9 5708.5 5711.8 5768.0 5779.5 5789.1 6201.0 6219.8 6300.3 6300.9 6324.6 6567.8 6685.3 6716.1 6755.8 6820.1 6761.2 6765.3 6781.2 6788.6 6810.1 6190.8 6201.3 6259.9 6279.1 6302.3 6994.4 7000.4 7008.1 7024.0 7053.5 6426.7 6527.2 6740.9 6751.2 6758.8 6690.6 6719.4 6719.6 6721.2 6722.3 6578.7 6605.9 6649.4 6652.8 6682.0 6670.1 6723.7 6748.6 6778.8 6782.7 5345.9 5426.8 5440.4 5505.4 5510.9 4624.3 4640.9 4670.5 4682.0 4732.0 5831.9 5836.5 5870.5 5877.1 5926.3 6804.8 6818.4 6887.4 6893.6 6896.8 5833.6 5863.0 5904.6 5925.8 5932.5 5904.9 5978.6 6000.2 6018.6 6065.4 6474.8 6540.5 6551.1 6558.0 6569.7 6663.7 6773.5 6774.8 6778.9 6786.3 6413.3 6517.6 6531.0 6534.1 6550.7 6675.9 6684.7 6741.8 6753.3 6767.7 6732.2 6769.7 6803.6 6825.8 6859.3 6421.8 6496.9 6549.9 6551.7 6639.4 6565.9 6586.9 6590.3 6597.1 6621.9 6498.7 6541.7 6590.5 6597.4 6603.2 6426.7 6494.1 6513.5 6536.5 6552.4 6372.2 6421.9 6424.4 6428.2 6436.9 04:10:07 INFO - PID 8906 | 04:10:07 INFO - PID 8906 | [#1] ext.html Cycles:180 Average:38214.17 Median:34056.99 stddev:53955.80 (158.4%) stddev-sans-first:54033.94 04:10:07 INFO - PID 8906 | Values: 778.2 838.5 873.3 876.5 1179.5 30589.5 41086.0 41856.8 42101.8 42103.6 42528.0 42883.8 42915.4 42986.8 43056.4 7535.4 7593.8 7723.3 7739.3 7746.8 7338.2 7653.9 7654.4 7674.4 7678.3 7644.4 7685.9 7686.0 7686.9 7698.9 7631.8 7651.1 7670.8 7671.7 7684.2 7473.1 7546.8 7557.3 7559.1 7559.9 38502.8 38735.4 38767.6 38800.4 38940.2 3196.4 3219.6 3232.4 3243.7 3260.8 30715.2 30730.8 30783.2 30795.8 30827.2 4901.6 4922.2 4948.4 4951.1 4972.5 191320.0 192841.0 192958.0 193399.0 194342.0 49830.8 49894.0 50006.2 50136.2 50149.7 52226.7 52293.2 52644.5 52670.5 52745.5 14679.0 16466.3 16888.0 16926.5 16951.5 12490.7 13911.5 14104.0 14154.1 14164.3 14527.1 14572.9 14613.6 14632.9 14691.1 5608.9 5644.3 5686.7 5778.1 5934.3 1011.7 1022.5 1031.7 1035.9 1047.7 22794.9 23007.3 23155.5 23287.8 23338.6 1125.6 1125.9 1128.6 1131.0 1134.0 12052.4 12547.7 12553.8 12582.6 12607.0 226475.0 227028.0 227076.0 227152.0 227348.0 52932.5 53173.5 53191.0 53366.0 53402.7 1431.5 1437.4 1481.1 1482.1 1506.2 23134.8 23145.0 23147.8 23213.7 23379.0 37546.0 37599.6 37621.2 37635.2 37692.1 202684.0 203516.0 203681.0 203852.0 203923.0 34411.1 35088.0 36782.4 36906.4 36954.1 46723.5 46807.3 47123.5 47220.0 47673.8 33291.7 33711.8 33738.3 33947.7 34160.7 33243.8 33363.7 33581.3 33725.2 33953.3 36899.8 37166.8 37362.8 37654.0 37865.2 32986.5 33432.7 33553.7 33733.7 33789.0 24655.6 25147.7 25153.7 25679.4 26356.5 04:10:07 INFO - PID 8906 | 04:10:07 INFO - PID 8906 | [#2] jquery.html Cycles:180 Average:33606.93 Median:12273.20 stddev:74072.47 (603.5%) stddev-sans-first:74238.71 04:10:07 INFO - PID 8906 | Values: 563.8 567.4 567.8 570.1 574.2 21988.6 22656.5 22656.5 22682.4 23693.4 25628.9 25960.6 26200.0 26205.9 26237.2 6325.5 6421.2 6477.1 6479.4 6480.7 6124.4 6332.9 6384.5 6409.9 6410.0 6380.9 6386.2 6387.0 6388.8 6390.4 6343.7 6354.6 6367.2 6373.3 6374.4 5951.9 5956.4 5961.5 5964.8 5966.0 28369.7 28694.6 28888.1 29043.4 29204.6 1889.7 1924.9 1930.6 1930.7 1931.8 18966.5 19058.5 20011.4 20166.8 20189.9 2206.9 2247.7 2398.8 2418.1 2466.3 140055.0 144852.0 149830.0 151376.0 151732.2 14809.8 14998.3 15339.9 15417.5 15510.4 23625.6 23634.3 23654.9 23671.4 23681.9 10278.5 10426.6 10581.9 10709.8 10837.1 9967.0 10034.0 10039.9 10057.2 10194.7 9367.7 9433.4 9452.0 9500.0 9538.0 2835.8 2858.0 2859.8 2880.5 2950.8 946.4 949.4 959.9 969.5 972.2 19489.1 19522.3 19804.9 19811.5 20036.0 1024.5 1030.4 1037.1 1039.8 1048.4 10619.4 10635.8 11024.0 11071.2 11197.3 423136.0 428587.0 428819.0 437466.0 441250.0 114942.0 116018.4 116470.0 116900.4 118519.4 1070.2 1088.4 1206.2 1213.0 1236.1 21023.7 21127.8 22117.1 22126.9 22298.7 56022.4 56733.0 58582.7 60804.4 61371.4 67769.0 68215.3 68289.3 68819.7 69144.0 14518.4 14718.4 14740.2 14768.0 14948.8 22938.9 23175.0 23307.2 23434.4 23474.4 18286.9 18305.3 18916.5 19131.7 19149.2 15993.0 15995.2 16437.7 16647.3 17155.2 18720.2 19338.0 19437.5 19519.8 19687.2 14512.3 14531.1 14653.9 14695.5 14697.6 18348.9 18494.2 18597.8 18648.3 18689.5 04:10:07 INFO - PID 8906 | 04:10:07 INFO - PID 8906 | [#3] mootools.html Cycles:180 Average:8908.26 Median:2628.07 stddev:6218.83 (236.6%) stddev-sans-first:6209.88 04:10:07 INFO - PID 8906 | Values: 1282.4 1312.6 1331.3 1335.6 1381.3 13921.2 14281.9 14435.9 14512.1 14521.9 14383.5 14769.3 14824.0 14826.6 14830.4 2756.0 2781.8 2783.9 2784.8 2801.8 2648.0 2717.0 2764.2 2765.2 2766.8 2617.5 2655.9 2657.9 2658.7 2666.9 2603.2 2620.0 2626.9 2627.2 2628.9 2618.1 2648.4 2649.6 2652.8 2661.3 13935.3 14165.5 14166.7 14184.1 14218.1 7657.2 7951.0 7960.8 7976.0 7978.2 5044.3 5267.3 5307.5 5352.1 5398.2 1833.5 1840.4 1871.2 1901.1 1903.8 23129.4 23144.8 23215.4 23300.1 23312.8 6328.4 6502.3 6541.4 6686.5 6692.7 15910.1 15910.4 15927.2 15948.0 15955.8 7403.3 7672.7 7673.2 7673.9 7677.9 5906.9 6139.9 6252.2 6267.5 6288.8 5169.1 5247.4 5352.9 5357.9 5358.3 1494.9 1516.2 1526.5 1530.9 1548.6 843.2 854.7 871.2 878.2 892.9 7229.8 7234.9 7266.7 7274.7 7281.6 708.2 708.8 709.1 710.9 729.9 3871.8 4546.2 5032.6 5103.6 5172.4 20020.0 20678.4 20787.3 20800.8 20839.5 19278.4 20436.6 20719.0 20770.4 20772.6 10358.5 11122.6 11299.5 11321.3 11380.4 8319.2 8554.7 8571.6 8685.0 8727.8 17418.9 18047.2 18144.1 18166.7 18266.5 17814.6 18086.5 18222.7 18591.3 18641.2 11336.8 11553.8 11562.8 11923.6 12062.6 13473.9 13658.0 13680.3 13685.5 13692.5 10725.1 10882.9 10967.6 10985.3 11221.8 10324.6 10407.9 10506.9 10627.3 10809.6 9720.6 9946.1 9952.1 9952.8 9963.1 6849.2 6865.5 6903.4 6937.6 7074.4 7059.3 7136.9 7164.9 7220.0 7297.4 04:10:07 INFO - PID 8906 | 04:10:07 INFO - PID 8906 | [#4] prototype.html Cycles:180 Average:5541.26 Median:6154.49 stddev:1563.39 (25.4%) stddev-sans-first:1521.21 04:10:07 INFO - PID 8906 | Values: 495.2 499.5 517.4 528.2 533.2 6204.5 6286.0 6304.0 6304.8 6320.7 5979.0 6050.0 6132.3 6158.1 6182.0 4934.1 4942.4 4994.4 5012.7 5055.2 4818.9 4976.4 4977.3 5010.6 5014.3 4909.2 4923.6 4963.6 4967.9 4970.0 4949.2 4959.6 4973.8 4987.4 5015.1 4390.6 4413.4 4459.8 4463.4 4476.8 6146.3 6150.5 6150.8 6173.8 6184.4 5735.3 5750.7 5769.8 5800.8 5804.0 6564.8 6584.9 6625.2 6636.1 6670.0 4867.3 4875.6 4890.4 4895.8 4904.3 6974.1 6974.4 6987.7 7006.4 7043.9 4999.7 5058.9 5059.2 5072.7 5075.2 5123.3 5143.9 5162.4 5165.2 5171.3 5721.7 5769.9 5773.0 5776.8 5803.0 6580.6 6600.3 6603.4 6618.0 6718.9 1659.6 1687.1 1720.8 1752.5 1753.4 1205.4 1213.8 1244.3 1247.1 1247.8 5696.3 5699.7 5709.6 5724.6 5736.9 6469.8 6705.9 6761.4 6778.9 6804.9 5787.0 5807.1 5833.5 5882.9 5889.2 3615.7 3615.9 3628.8 3641.8 3649.6 6478.6 6550.2 6565.4 6616.5 6642.8 6957.2 6983.5 7032.7 7056.4 7111.5 6561.7 6565.2 6601.4 6616.6 6623.9 6803.5 6808.7 6817.7 6846.7 6857.5 6580.7 6593.9 6699.9 6730.4 6776.8 6419.6 6474.5 6531.2 6532.6 6543.7 4869.2 4962.6 4978.9 4980.5 5015.3 6493.0 6739.4 6805.5 6842.2 6894.2 6223.3 6430.6 6461.1 6511.0 6575.5 6485.0 6513.2 6525.8 6542.1 6571.5 6429.6 6469.8 6488.5 6520.9 6534.7 6523.4 6580.8 6595.9 6623.2 6649.3 6411.3 6434.5 6448.5 6462.4 6463.4 04:10:07 INFO - PID 8906 | 04:10:07 INFO - PID 8906 | [#5] yui.html Cycles:180 Average:24642.18 Median:19426.06 stddev:33772.80 (173.9%) stddev-sans-first:33820.37 04:10:07 INFO - PID 8906 | Values: 870.7 906.0 912.5 915.6 920.5 18230.1 18784.2 19816.8 19819.2 19834.2 20379.8 21342.4 21605.8 21642.7 21802.9 820.7 1062.5 1064.4 1065.8 1066.3 1055.0 1069.4 1069.9 1071.0 1071.2 1105.7 1127.2 1130.8 1130.9 1131.2 1109.3 1119.0 1119.1 1119.4 1120.2 810.0 810.1 814.1 815.5 816.4 17735.6 17744.4 17808.0 20278.7 20358.8 20560.2 20655.7 20791.2 21000.5 21192.9 16253.1 16458.0 16460.7 17054.5 17905.6 3669.1 3686.7 3687.3 3690.5 3692.2 126973.0 128218.0 130586.0 131756.0 133214.3 16372.8 16744.2 16973.3 17004.2 17277.8 31141.8 31275.5 31283.5 31308.4 31860.8 7813.1 7955.4 8273.5 8386.2 8435.2 3734.7 4291.2 4305.5 4323.7 4324.8 5306.4 5365.9 5381.0 5411.8 5418.6 1032.9 1046.2 1053.1 1058.4 1059.4 701.2 713.3 713.8 715.2 732.6 12072.2 12396.9 12446.3 12748.6 12837.3 698.9 705.3 705.5 707.5 715.2 5695.3 5717.5 5731.6 5869.4 5900.8 113402.0 114718.0 115241.4 115967.4 117672.4 103326.0 105866.0 106013.5 106312.0 107267.0 85983.6 88784.0 90436.0 91152.4 94296.0 12293.8 13042.5 13047.5 13208.3 13506.3 39254.6 40072.8 40561.4 41620.4 42639.0 70650.6 74214.0 75007.0 76142.6 76490.2 18245.8 18407.0 18482.5 18860.0 18974.6 18758.8 19028.1 19573.1 19877.2 22315.1 17086.5 18641.3 19103.5 19191.3 19371.9 17594.4 17715.5 18904.8 19480.2 20560.3 17937.2 18057.5 19579.9 19723.5 19905.7 13367.5 13693.9 13823.8 14287.9 14387.4 14530.6 14744.2 14749.9 14806.2 14982.0 04:10:07 INFO - PID 8906 | -------- Summary: end -------- 04:10:07 INFO - PID 8906 | 04:10:08 INFO - TEST-INFO | 8906: exit 0 04:10:08 INFO - TEST-OK | dromaeo_css | took 1114039ms 04:10:08 INFO - TEST-START | kraken 04:10:08 INFO - Initialising browser for kraken test... 04:10:08 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58226/getInfo.html -profile /tmp/tmpuRAxhl/profile 04:10:08 INFO - TEST-INFO | started process 9279 (/builds/slave/test/build/application/firefox/firefox http://localhost:58226/getInfo.html) 04:10:14 INFO - TEST-INFO | 9279: exit 0 04:10:14 INFO - Browser initialized. 04:10:14 INFO - Running cycle 1/1 for kraken test... 04:10:14 INFO - TEST-INFO | started process 9513 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuRAxhl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:10:15 INFO - PID 9513 | 04:10:15 INFO - PID 9513 | (/builds/slave/test/build/application/firefox/firefox:9567): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:10:15 INFO - PID 9513 | 04:10:26 INFO - PID 9513 | RSS: Main: 171802624 04:10:26 INFO - PID 9513 | 04:10:28 INFO - PID 9513 | Cycle 1(1): loaded http://localhost:58226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58226/tests/kraken/driver.html?testName=audio-beat-detection) 04:10:29 INFO - PID 9513 | RSS: Main: 177672192 04:10:29 INFO - PID 9513 | 04:10:32 INFO - PID 9513 | Cycle 1(1): loaded http://localhost:58226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58226/tests/kraken/driver.html?testName=audio-dft) 04:10:33 INFO - PID 9513 | RSS: Main: 177983488 04:10:33 INFO - PID 9513 | 04:10:36 INFO - PID 9513 | Cycle 1(1): loaded http://localhost:58226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58226/tests/kraken/driver.html?testName=audio-fft) 04:10:36 INFO - PID 9513 | RSS: Main: 173490176 04:10:36 INFO - PID 9513 | 04:10:38 INFO - PID 9513 | Cycle 1(1): loaded http://localhost:58226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58226/tests/kraken/driver.html?testName=audio-oscillator) 04:10:39 INFO - PID 9513 | RSS: Main: 173535232 04:10:39 INFO - PID 9513 | 04:10:41 INFO - PID 9513 | Cycle 1(1): loaded http://localhost:58226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:10:42 INFO - PID 9513 | RSS: Main: 173748224 04:10:42 INFO - PID 9513 | 04:10:45 INFO - PID 9513 | Cycle 1(1): loaded http://localhost:58226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58226/tests/kraken/driver.html?testName=imaging-darkroom) 04:10:46 INFO - PID 9513 | RSS: Main: 176558080 04:10:46 INFO - PID 9513 | 04:10:49 INFO - PID 9513 | Cycle 1(1): loaded http://localhost:58226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58226/tests/kraken/driver.html?testName=imaging-desaturate) 04:10:50 INFO - PID 9513 | RSS: Main: 176119808 04:10:50 INFO - PID 9513 | 04:10:53 INFO - PID 9513 | Cycle 1(1): loaded http://localhost:58226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58226/tests/kraken/driver.html?testName=json-parse-financial) 04:10:53 INFO - PID 9513 | RSS: Main: 177684480 04:10:53 INFO - PID 9513 | 04:10:55 INFO - PID 9513 | Cycle 1(1): loaded http://localhost:58226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:10:56 INFO - PID 9513 | RSS: Main: 177553408 04:10:56 INFO - PID 9513 | 04:10:58 INFO - PID 9513 | Cycle 1(1): loaded http://localhost:58226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58226/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:10:58 INFO - PID 9513 | RSS: Main: 174485504 04:10:58 INFO - PID 9513 | 04:11:00 INFO - PID 9513 | Cycle 1(1): loaded http://localhost:58226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:11:01 INFO - PID 9513 | RSS: Main: 174956544 04:11:01 INFO - PID 9513 | 04:11:04 INFO - PID 9513 | Cycle 1(1): loaded http://localhost:58226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:11:05 INFO - PID 9513 | RSS: Main: 174804992 04:11:05 INFO - PID 9513 | 04:11:08 INFO - PID 9513 | Cycle 1(1): loaded http://localhost:58226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:11:08 INFO - PID 9513 | RSS: Main: 174903296 04:11:08 INFO - PID 9513 | 04:11:10 INFO - PID 9513 | Cycle 1(1): loaded http://localhost:58226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58226/tests/kraken/driver.html?testName=ai-astar) 04:11:10 INFO - PID 9513 | RSS: Main: 176316416 04:11:10 INFO - PID 9513 | 04:11:10 INFO - PID 9513 | __start_tp_report 04:11:10 INFO - PID 9513 | _x_x_mozilla_page_load 04:11:10 INFO - PID 9513 | _x_x_mozilla_page_load_details 04:11:10 INFO - PID 9513 | |i|pagename|runs| 04:11:10 INFO - PID 9513 | |0;ai-astar;98;116;106;106;109;110;110;99;108;110 04:11:10 INFO - PID 9513 | |1;audio-beat-detection;146;119;145;174;120;150;145;125;177;124 04:11:10 INFO - PID 9513 | |2;audio-dft;163;165;163;168;164;166;178;167;167;174 04:11:10 INFO - PID 9513 | |3;audio-fft;76;73;76;174;77;76;76;76;155;75 04:11:10 INFO - PID 9513 | |4;audio-oscillator;84;80;135;80;79;80;131;81;79;132 04:11:10 INFO - PID 9513 | |5;imaging-gaussian-blur;113;114;115;114;115;114;116;116;121;121 04:11:10 INFO - PID 9513 | |6;imaging-darkroom;152;152;156;157;156;152;154;153;157;151 04:11:10 INFO - PID 9513 | |7;imaging-desaturate;94;94;93;94;94;94;102;94;94;94 04:11:10 INFO - PID 9513 | |8;json-parse-financial;55;54;54;54;57;56;54;53;54;54 04:11:10 INFO - PID 9513 | |9;json-stringify-tinderbox;54;60;53;54;54;54;63;55;54;57 04:11:10 INFO - PID 9513 | |10;stanford-crypto-aes;79;83;79;78;79;84;82;81;85;79 04:11:10 INFO - PID 9513 | |11;stanford-crypto-ccm;127;136;128;127;138;131;130;132;124;160 04:11:10 INFO - PID 9513 | |12;stanford-crypto-pbkdf2;182;177;177;181;176;179;183;179;190;185 04:11:10 INFO - PID 9513 | |13;stanford-crypto-sha256-iterative;68;73;67;68;66;67;72;68;67;71 04:11:10 INFO - PID 9513 | __end_tp_report 04:11:10 INFO - PID 9513 | __start_cc_report 04:11:10 INFO - PID 9513 | _x_x_mozilla_cycle_collect,960 04:11:10 INFO - PID 9513 | __end_cc_report 04:11:10 INFO - PID 9513 | __startTimestamp1502449870943__endTimestamp 04:11:10 INFO - PID 9513 | 04:11:10 INFO - PID 9513 | ------- Summary: start ------- 04:11:10 INFO - PID 9513 | Number of tests: 14 04:11:10 INFO - PID 9513 | 04:11:10 INFO - PID 9513 | [#0] ai-astar Cycles:10 Average:107.20 Median:110.00 stddev:5.37 (4.9%) stddev-sans-first:4.55 04:11:10 INFO - PID 9513 | Values: 98.0 116.0 106.0 106.0 109.0 110.0 110.0 99.0 108.0 110.0 04:11:10 INFO - PID 9513 | 04:11:10 INFO - PID 9513 | [#1] audio-beat-detection Cycles:10 Average:142.50 Median:145.50 stddev:21.00 (14.4%) stddev-sans-first:22.24 04:11:10 INFO - PID 9513 | Values: 146.0 119.0 145.0 174.0 120.0 150.0 145.0 125.0 177.0 124.0 04:11:10 INFO - PID 9513 | 04:11:10 INFO - PID 9513 | [#2] audio-dft Cycles:10 Average:167.50 Median:167.00 stddev:4.88 (2.9%) stddev-sans-first:4.90 04:11:10 INFO - PID 9513 | Values: 163.0 165.0 163.0 168.0 164.0 166.0 178.0 167.0 167.0 174.0 04:11:10 INFO - PID 9513 | 04:11:10 INFO - PID 9513 | [#3] audio-fft Cycles:10 Average:93.40 Median:76.00 stddev:37.75 (49.7%) stddev-sans-first:39.52 04:11:10 INFO - PID 9513 | Values: 76.0 73.0 76.0 174.0 77.0 76.0 76.0 76.0 155.0 75.0 04:11:10 INFO - PID 9513 | 04:11:10 INFO - PID 9513 | [#4] audio-oscillator Cycles:10 Average:96.10 Median:80.00 stddev:25.29 (31.6%) stddev-sans-first:26.44 04:11:10 INFO - PID 9513 | Values: 84.0 80.0 135.0 80.0 79.0 80.0 131.0 81.0 79.0 132.0 04:11:10 INFO - PID 9513 | 04:11:10 INFO - PID 9513 | [#5] imaging-gaussian-blur Cycles:10 Average:115.90 Median:115.50 stddev:2.85 (2.5%) stddev-sans-first:2.82 04:11:10 INFO - PID 9513 | Values: 113.0 114.0 115.0 114.0 115.0 114.0 116.0 116.0 121.0 121.0 04:11:10 INFO - PID 9513 | 04:11:10 INFO - PID 9513 | [#6] imaging-darkroom Cycles:10 Average:154.00 Median:155.00 stddev:2.31 (1.5%) stddev-sans-first:2.33 04:11:10 INFO - PID 9513 | Values: 152.0 152.0 156.0 157.0 156.0 152.0 154.0 153.0 157.0 151.0 04:11:10 INFO - PID 9513 | 04:11:10 INFO - PID 9513 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:94.00 stddev:2.58 (2.7%) stddev-sans-first:2.73 04:11:10 INFO - PID 9513 | Values: 94.0 94.0 93.0 94.0 94.0 94.0 102.0 94.0 94.0 94.0 04:11:10 INFO - PID 9513 | 04:11:10 INFO - PID 9513 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:54.00 stddev:1.18 (2.2%) stddev-sans-first:1.24 04:11:10 INFO - PID 9513 | Values: 55.0 54.0 54.0 54.0 57.0 56.0 54.0 53.0 54.0 54.0 04:11:10 INFO - PID 9513 | 04:11:10 INFO - PID 9513 | [#9] json-stringify-tinderbox Cycles:10 Average:55.80 Median:54.50 stddev:3.26 (6.0%) stddev-sans-first:3.39 04:11:10 INFO - PID 9513 | Values: 54.0 60.0 53.0 54.0 54.0 54.0 63.0 55.0 54.0 57.0 04:11:10 INFO - PID 9513 | 04:11:10 INFO - PID 9513 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:81.50 stddev:2.47 (3.0%) stddev-sans-first:2.52 04:11:10 INFO - PID 9513 | Values: 79.0 83.0 79.0 78.0 79.0 84.0 82.0 81.0 85.0 79.0 04:11:10 INFO - PID 9513 | 04:11:10 INFO - PID 9513 | [#11] stanford-crypto-ccm Cycles:10 Average:133.30 Median:131.50 stddev:10.30 (7.8%) stddev-sans-first:10.67 04:11:10 INFO - PID 9513 | Values: 127.0 136.0 128.0 127.0 138.0 131.0 130.0 132.0 124.0 160.0 04:11:10 INFO - PID 9513 | 04:11:10 INFO - PID 9513 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.90 Median:181.50 stddev:4.31 (2.4%) stddev-sans-first:4.55 04:11:10 INFO - PID 9513 | Values: 182.0 177.0 177.0 181.0 176.0 179.0 183.0 179.0 190.0 185.0 04:11:10 INFO - PID 9513 | 04:11:10 INFO - PID 9513 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:68.00 stddev:2.41 (3.5%) stddev-sans-first:2.54 04:11:10 INFO - PID 9513 | Values: 68.0 73.0 67.0 68.0 66.0 67.0 72.0 68.0 67.0 71.0 04:11:10 INFO - PID 9513 | -------- Summary: end -------- 04:11:10 INFO - PID 9513 | 04:11:11 INFO - PID 9513 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:11:11 INFO - TEST-INFO | 9513: exit 0 04:11:11 INFO - TEST-OK | kraken | took 63183ms 04:11:11 INFO - SUITE-END | took 1177s 04:11:12 INFO - Completed test suite (00:19:37) 04:11:12 INFO - javascript benchmark 04:11:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8561.632112463007, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3140.1364624728203, 3149.4016136933983, 3177.714005739483, 3193.8083714977097, 3205.967940320596, 6133.907991380132, 6217.37826217378, 6236.06459031145, 6244.594101383407, 6260.7808726694575, 6191.783287584935, 6241.282252540959, 6291.559590828644, 6345.048242763588, 6357.014662727284, 5761.884762304751, 5790.971045144779, 5799.82600521985, 5825.766969321243, 5833.125031245305, 5750.3962083981205, 5781.248437703092, 5781.335146458148, 5782.595218334718, 5865.648061116325, 5601.719914004283, 5670.234518340054, 5684.715764211794, 5706.457886500776, 5713.942860571383, 5626.437356264381, 5688.459596338341, 5705.71471426427, 5707.172459993278, 5721.8569535761935, 5708.4862362387585, 5711.779423638621, 5768.048272035109, 5779.45095215958, 5789.131630255453, 6200.968995155041, 6219.751209951642, 6300.30696623371, 6300.905486417708, 6324.557280990332, 6567.802965911031, 6685.331466853276, 6716.0933274007875, 6755.831104222385, 6820.147481564804, 6761.222459417181, 6765.255821859591, 6781.22015968165, 6788.59268443895, 6810.080639113706, 6190.783322583733, 6201.255849298113, 6259.906101408483, 6279.089532017878, 6302.275238347649, 6994.4404447644065, 7000.369966703021, 7008.123984501937, 7024.0, 7053.470989675795, 6426.678666066678, 6527.15147030883, 6740.932590674129, 6751.156105486814, 6758.83102922421, 6690.632015239208, 6719.395254427125, 6719.563228390237, 6721.227058888193, 6722.327767223336, 6578.671066446755, 6605.900911486331, 6649.368310010541, 6652.83367915806, 6681.966590167018, 6670.132882725215, 6723.697433615499, 6748.628825414649, 6778.830529236809, 6782.728690852409, 5345.946540534623, 5426.810061647823, 5440.401555828856, 5505.366882808448, 5510.9448905510435, 4624.28323609841, 4640.86077417678, 4670.462896766848, 4681.976590117096, 4731.95268047322, 5831.941680583224, 5836.474717275466, 5870.530357571384, 5877.0596704527925, 5926.259217597801, 6804.795856124324, 6818.420434263143, 6887.449004079662, 6893.620850853151, 6896.793096207121, 5833.562482813806, 5863.003289440805, 5904.5571582131515, 5925.822225333247, 5932.495737862244, 5904.881902361929, 5978.611390259619, 6000.219971403777, 6018.638881667114, 6065.3934606539, 6474.805755827333, 6540.4767618590395, 6551.148350714377, 6557.983512555562, 6569.704363303663, 6663.666816659051, 6773.458123350219, 6774.762883299061, 6778.932210678027, 6786.287439818748, 6413.294537600954, 6517.608943463408, 6530.967345163243, 6534.085228068169, 6550.737970481318, 6675.933240667726, 6684.665766711548, 6741.797746067626, 6753.257141714505, 6767.661616919233, 6732.192136943598, 6769.695363708546, 6803.625800581016, 6825.761098361533, 6859.279775623488, 6421.775237866839, 6496.935030649822, 6549.901751473636, 6551.705173267119, 6639.402453779183, 6565.868682626417, 6586.934130658824, 6590.340965903371, 6597.109390232449, 6621.900671490028, 6498.675066246763, 6541.672916354259, 6590.47276217911, 6597.439217666552, 6603.240627327918, 6426.742930282736, 6494.1232933553365, 6513.54405191632, 6536.509761767791, 6552.410283074546, 6372.203474565679, 6421.935780642134, 6424.421802037839, 6428.228612566522, 6436.935630643634], "value": 6150.663871203076, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [778.1829079466562, 838.4717627894428, 873.2969959182856, 876.4609764994528, 1179.5222934711435, 30589.54115688264, 41086.0, 41856.79071604642, 42101.7894910525, 42103.57896421035, 42528.0, 42883.78558107205, 42915.35626965598, 42986.785066074626, 43056.3541546877, 7535.434842386816, 7593.77218683439, 7723.304902558769, 7739.3421559167255, 7746.767596972099, 7338.156112047102, 7653.923460765404, 7654.387648988068, 7674.386049116085, 7678.270564296383, 7644.350230230437, 7685.923140768605, 7686.0, 7686.884696729554, 7698.884516732225, 7631.809204769869, 7651.081870175587, 7670.808229794244, 7671.7314893979, 7684.231576842327, 7473.140588832245, 7546.811329716801, 7557.2820582044615, 7559.055118110236, 7559.924400756032, 38502.80748596239, 38735.418968715494, 38767.61232387668, 38800.417993730116, 38940.22119557593, 3196.4246435641576, 3219.6297425795865, 3232.385846689145, 3243.6918492743152, 3260.8043517388796, 30715.232119196975, 30730.84634576835, 30783.2304192397, 30795.846020769754, 30827.229319266975, 4901.583365413909, 4922.237053256751, 4948.406191257036, 4951.133551628486, 4972.5276098770555, 191320.0, 192841.0, 192958.0, 193399.0, 194342.0, 49830.750846246265, 49894.0, 50006.24990625143, 50136.247956280684, 50149.74925125351, 52226.73886630542, 52293.215601766, 52644.473555264725, 52670.47329526656, 52745.47254527405, 14679.045862019148, 16466.259018344204, 16888.0, 16926.492205233862, 16951.49145525636, 12490.687732806753, 13911.513097041556, 14104.0, 14154.079984800956, 14164.291785410689, 14527.055741376776, 14572.927135364256, 14613.634659133606, 14632.926835365755, 14691.11853288806, 5608.9439105609235, 5644.266245388073, 5686.658800471985, 5778.104393818965, 5934.287885453773, 1011.6813203840767, 1022.5245260953543, 1031.6750223679667, 1035.9067683908484, 1047.7014050995583, 22794.886025569765, 23007.30978070627, 23155.53688926212, 23287.7671223289, 23338.64992025121, 1125.6004118537965, 1125.9155563332783, 1128.6331942118845, 1130.9990658267327, 1133.9943300283446, 12052.397380130958, 12547.686307842378, 12553.811692824615, 12582.622521324374, 12607.0, 226475.0, 227028.0, 227076.0, 227152.0, 227348.0, 52932.47067529352, 53173.46826531763, 53191.0, 53366.0, 53402.73298633482, 1431.4918204037624, 1437.3696333422204, 1481.0743285446597, 1482.1033274868548, 1506.164078936201, 23134.76865231326, 23145.0, 23147.76852231456, 23213.651795222748, 23379.0, 37546.0, 37599.624003760706, 37621.247575048896, 37635.24729505449, 37692.05769855666, 202684.0, 203516.0, 203681.0, 203852.0, 203923.0, 34411.13972150716, 35088.0, 36782.44826327554, 36906.44640330451, 36954.07614809544, 46723.532764671916, 46807.29789053235, 47123.52876471191, 47220.0, 47673.76163119162, 33291.66708332886, 33711.83144084264, 33738.325233495685, 33947.66052339445, 34160.65839341674, 33243.833780830944, 33363.66636333606, 33581.32837343288, 33725.15687107842, 33953.32093358169, 36899.81550092233, 37166.81416593008, 37362.8131859339, 37654.0, 37865.242695146495, 32986.505202421504, 33432.665673342955, 33553.66446335505, 33733.662663373056, 33789.0, 24655.63016554789, 25147.74852251454, 25153.748462515872, 25679.358016049748, 26356.472870542864], "value": 17414.21908613177, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.8308902307164, 567.4155619711696, 567.7615401531358, 570.133397236201, 574.2305310883416, 21988.560228795413, 22656.546869062608, 22656.546869062608, 22682.43293917651, 23693.407664808346, 25628.871855640744, 25960.610590841152, 26200.0, 26205.86897065512, 26237.212883613476, 6325.525585581088, 6421.229452465687, 6477.093206951031, 6479.384458476437, 6480.74077036918, 6124.387561243884, 6332.873342533147, 6384.48924086072, 6409.8718025639455, 6409.967950160242, 6380.872382552347, 6386.1697979262635, 6387.041943708435, 6388.840278993016, 6390.3609639036185, 6343.682815859189, 6354.5551811373225, 6367.204099487564, 6373.330800265951, 6374.426301632852, 5951.91072133914, 5956.404359564052, 5961.493273071794, 5964.821055368302, 5966.0, 28369.716302836914, 28694.569581456086, 28888.133355999144, 29043.419131617462, 29204.561931571043, 1889.7354370388157, 1924.9230030798751, 1930.6138772245606, 1930.7200455933812, 1931.7875033746418, 18966.525836854053, 19058.52353691155, 20011.399658010283, 20166.798332016784, 20189.899050504653, 2206.8896555172178, 2247.741509726386, 2398.787404654805, 2418.129473389596, 2466.3340897957632, 140055.0, 144852.0, 149830.0, 151376.0, 151732.2413387926, 14809.777853332209, 14998.250087495582, 15339.92330038365, 15417.537473875802, 15510.379584816706, 23625.645615315785, 23634.29097127089, 23654.881725591607, 23671.408214794767, 23681.881590591936, 10278.537465814057, 10426.582936682447, 10581.947090264499, 10709.78580428387, 10837.133029357632, 9966.950165249127, 10034.0, 10039.899601003897, 10057.245706572035, 10194.694159175237, 9367.672131475365, 9433.386829856037, 9451.952740236393, 9500.0, 9537.998510156473, 2835.844028578448, 2858.0, 2859.771218302531, 2880.4815133275793, 2950.7786915981387, 946.3801210207325, 949.4161090929167, 959.8656188133667, 969.5297780576415, 972.1687956796978, 19489.122989465508, 19522.316718914768, 19804.90097549532, 19811.50471238202, 20036.0, 1024.4519182237468, 1030.4229631406436, 1037.1339931157418, 1039.7920415916697, 1048.3500229857384, 10619.415932123808, 10635.787284254271, 11024.007839294502, 11071.225014249087, 11197.272177308449, 423136.0, 428587.0, 428819.0, 437466.0, 441250.0, 114942.0, 116018.41990789992, 116470.0, 116900.41549792196, 118519.40740296243, 1070.1920050361928, 1088.4285749981166, 1206.1737709668905, 1212.9757404851853, 1236.110000799423, 21023.684644730034, 21127.788722112582, 22117.115315387204, 22126.88936555307, 22298.665520017537, 56022.43977560172, 56733.0, 58582.707086464296, 60804.391956079875, 61371.38628613835, 67769.0, 68215.3178468209, 68289.31710683028, 68819.65590172017, 69144.0, 14518.419263229353, 14718.411263549338, 14740.189289588747, 14768.0, 14948.775768363701, 22938.885305573367, 23175.0, 23307.184248551897, 23434.414139646644, 23474.41313967096, 18286.908565457088, 18305.26778928828, 18916.527086822938, 19131.713024304925, 19149.234030638618, 15992.960457570221, 15995.200239988188, 16437.671246574762, 16647.334106636084, 17155.228014739117, 18720.157592908625, 19338.0, 19437.51406214856, 19519.8048019518, 19687.21251149995, 14512.346944387316, 14531.128132312095, 14653.92673036628, 14695.485658001919, 14697.55907322782, 18348.90825545864, 18494.16776245099, 18597.81402186015, 18648.254069837054, 18689.53276168107], "value": 12039.4522121736, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1282.3780466473759, 1312.5668529385305, 1331.26780270851, 1335.6193484856813, 1381.3024422666556, 13921.1647301162, 14281.928590357034, 14435.927820360883, 14512.12927224366, 14521.927390363047, 14383.496577619786, 14769.261536923137, 14824.0, 14826.55520334389, 14830.406783728666, 2755.9586606200874, 2781.7913656475744, 2783.9443211135867, 2784.8050636455455, 2801.80387372884, 2648.0, 2717.0490328385104, 2764.1707487753692, 2765.2395591212476, 2766.7786577073784, 2617.4765046990583, 2655.9468810623775, 2657.853818040007, 2658.7341265873356, 2666.8666566671686, 2603.2450589329164, 2619.9607005894736, 2626.8817903194404, 2627.2118364490575, 2628.921132366032, 2618.0967566189634, 2648.3776312566533, 2649.5893136563864, 2652.840829550214, 2661.334666333417, 13935.303234838217, 14165.504207352797, 14166.716665666629, 14184.078034927697, 14218.07582507144, 7657.157712651604, 7950.960245198795, 7960.761177164694, 7976.0, 7978.162292959214, 5044.293798868125, 5267.26258323835, 5307.522322990911, 5352.143657014898, 5398.24424580559, 1833.4957886581296, 1840.4386662068064, 1871.1579789094826, 1901.077977181066, 1903.8286554210188, 23129.421764455856, 23144.76855231443, 23215.419614509603, 23300.067997280257, 23312.7668723314, 6328.398802113792, 6502.349765023459, 6541.443977261891, 6686.498512611476, 6692.698828552727, 15910.12494312824, 15910.44313449047, 15927.203639817963, 15948.0, 15955.760663590056, 7403.333699967029, 7672.65473053714, 7673.232676732282, 7673.923260767432, 7677.884831727529, 5906.911396329058, 6139.877202455926, 6252.218472690914, 6267.467265282411, 6288.842778930563, 5169.121249387613, 5247.422783493813, 5352.946470535323, 5357.866053348619, 5358.303420555303, 1494.9177795221149, 1516.219147139224, 1526.4886263101985, 1530.9387624495118, 1548.6051056980623, 843.2495079379357, 854.679495189304, 871.2028493928111, 878.2139984713646, 892.8571428571528, 7229.819254518679, 7234.855302893912, 7266.672999715025, 7274.709011639581, 7281.635918204069, 708.1997343002374, 708.7661071846278, 709.1206903439697, 710.9158533236855, 729.9343059124704, 3871.845126194977, 4546.158960592301, 5032.647714660012, 5103.617228707788, 5172.431032586413, 20020.0, 20678.379648610568, 20787.272445464336, 20800.791992080187, 20839.47901302449, 19278.421647350602, 20436.591268174554, 20719.0, 20770.376888693365, 20772.584548309253, 10358.482075896174, 11122.61070862516, 11299.491522881326, 11321.320720756781, 11380.374079425712, 8319.209675080858, 8554.700585479728, 8571.614277357408, 8685.0, 8727.825443491221, 17418.9129054359, 18047.18787654585, 18144.092795359917, 18166.72749908726, 18266.452006439828, 17814.643707125524, 18086.457406277834, 18222.72665910039, 18591.256349745858, 18641.161147748113, 11336.75295717487, 11553.768924621292, 11562.826557601811, 11923.642290731292, 12062.577809776967, 13473.932630336785, 13658.04393692432, 13680.315984200552, 13685.452581896612, 13692.52076177329, 10725.08836748885, 10882.891171088188, 10967.616133435291, 10985.285956412808, 11221.775564488502, 10324.587016519255, 10407.895921040692, 10506.947465262625, 10627.25609207379, 10809.56761729522, 9720.611175552898, 9946.055124763136, 9952.10431061208, 9952.751181220527, 9963.053509916546, 6849.212340580897, 6865.450763938973, 6903.378695917391, 6937.618430986344, 7074.398676112588, 7059.258777828254, 7136.892946605909, 7164.928350716426, 7219.963900180465, 7297.416206703557], "value": 6393.223918860471, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.20025159367634, 499.52485434671195, 517.3998162131928, 528.2446102074034, 533.1868899927609, 6204.534659900514, 6285.9999999999945, 6304.0, 6304.842378940529, 6320.715567799449, 5978.970105149479, 6049.969750151243, 6132.264128304599, 6158.137860699506, 6181.969090154543, 4934.087193869137, 4942.382202224722, 4994.375703037121, 5012.674176178555, 5055.191169412895, 4818.927716084244, 4976.427710813248, 4977.303177555145, 5010.624203184758, 5014.2979982802435, 4909.214525675892, 4923.630727695419, 4963.553280204781, 4967.875803104916, 4969.950300496985, 4949.208126699711, 4959.628027897904, 4973.781423551217, 4987.426445958725, 5015.122353588125, 4390.560943905616, 4413.44831896013, 4459.777011149429, 4463.375127482155, 4476.776161191928, 6146.262448506208, 6150.4783038954265, 6150.846228844314, 6173.783917562909, 6184.443400093968, 5735.254416925814, 5750.683712395817, 5769.769209231626, 5800.7679692812235, 5804.042333015047, 6564.770233041869, 6584.93415065848, 6625.238097618787, 6636.137302150738, 6669.966650166767, 4867.342908707315, 4875.561199492027, 4890.437599676012, 4895.804167833282, 4904.313396124509, 6974.140602353062, 6974.4420446364165, 6987.685554150075, 7006.404455621278, 7043.8591228175665, 4999.725015124131, 5058.924116138261, 5059.19052951526, 5072.67027643202, 5075.168247972757, 5123.308353372247, 5143.922841157386, 5162.406323272797, 5165.225216217522, 5171.250168725589, 5721.713914304268, 5769.913451298234, 5773.04744717117, 5776.797812076557, 5802.970985145047, 6580.605163690098, 6600.273969863311, 6603.448189675422, 6617.9669101655145, 6718.899216511756, 1659.585103724069, 1687.1226961979946, 1720.7849018872641, 1752.509297396731, 1753.3512600337956, 1205.391277404905, 1213.7633161533593, 1244.3156264054733, 1247.077162899466, 1247.8315427417365, 5696.291112666184, 5699.74351154199, 5709.600327977085, 5724.599278050497, 5736.942630573641, 6469.77355792545, 6705.932940670628, 6761.377269455394, 6778.864422711518, 6804.863902721918, 5786.971065144648, 5807.12893066035, 5833.4749872511675, 5882.852928676817, 5889.1755154278435, 3615.7468977171347, 3615.897151368805, 3628.76413033152, 3641.7632853864943, 3649.59854416014, 6478.611283323015, 6550.246721627074, 6565.441937435371, 6616.503762217853, 6642.767503137367, 6957.234704182536, 6983.533457973781, 7032.718691252395, 7056.400205982446, 7111.502194846311, 6561.687662467526, 6565.21217453899, 6601.382661248025, 6616.569922954993, 6623.900641490285, 6803.489738269748, 6808.659567021728, 6817.7272909083085, 6846.657667116724, 6857.485688573477, 6580.696756135506, 6593.900866592694, 6699.851069997094, 6730.427913627396, 6776.796696099125, 6419.550631455753, 6474.4820414365795, 6531.183602049744, 6532.5753826001155, 6543.738250469928, 4869.245266983623, 4962.578180854668, 4978.875528111681, 4980.452150263541, 5015.322931404214, 6493.0, 6739.393454589111, 6805.489588280998, 6842.1789385272095, 6894.24163342042, 6223.315435302204, 6430.582012169194, 6461.092508371158, 6511.0, 6575.50683698715, 6485.0, 6513.15329007245, 6525.771597994048, 6542.1214665547, 6571.507136964842, 6429.58207716497, 6469.79745026215, 6488.545801793829, 6520.869582608227, 6534.705938232696, 6523.412892839668, 6580.80257592273, 6595.868082638416, 6623.172103487064, 6649.268580456049, 6411.294757576569, 6434.549581529435, 6448.5486015980305, 6462.447333303413, 6463.385978332051], "value": 5079.786162463126, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [870.677849195798, 905.9592318345675, 912.4570880326204, 915.5605309451463, 920.5443305563747, 18230.088495575234, 18784.15471303791, 19816.801831981677, 19819.207231710716, 19834.20663173473, 20379.796202037975, 21342.35972920811, 21605.783942160575, 21642.675359869616, 21802.89098554505, 820.7004443378169, 1062.5484169228087, 1064.393295821382, 1065.8081545321838, 1066.2749330455288, 1054.9947250263738, 1069.4064794039305, 1069.9411532365716, 1071.036067539213, 1071.1591400750413, 1105.6793529876327, 1127.216584473791, 1130.8473356096904, 1130.8586426696663, 1131.1911982932202, 1109.3454861631635, 1119.0152665654255, 1119.1326721790597, 1119.4067144413514, 1120.1990576737671, 810.0158307656226, 810.080558566026, 814.0963530481162, 815.502543448496, 816.4244207833464, 17735.645287094314, 17744.37894673693, 17808.0, 20278.69581956272, 20358.796412035837, 20560.177592896267, 20655.69016464754, 20791.168353266003, 21000.474988125417, 21192.894035529724, 16253.106079165642, 16458.0, 16460.6707865842, 17054.488365349058, 17905.641887162314, 3669.082729317671, 3686.6866316362875, 3687.2625474904858, 3690.4833323334487, 3692.2061756722433, 126973.0, 128218.0, 130586.0, 131755.99999999904, 133214.33392832972, 16372.83627163713, 16744.24650890713, 16973.321067157176, 17004.234809433605, 17277.82722172787, 31141.8442907784, 31275.530867037014, 31283.530747038814, 31308.373832523223, 31860.840695796374, 7813.101493328227, 7955.363570914313, 8273.544955027532, 8386.245237928615, 8435.24082832548, 3734.7012239020814, 4291.249031419551, 4305.461817272841, 4323.697341186087, 4324.827006919751, 5306.389765176977, 5365.865853353697, 5381.031414345455, 5411.83764487066, 5418.647787893853, 1032.8708911386077, 1046.1735229168871, 1053.1259054984498, 1058.3596923861107, 1059.4437920092014, 701.1831216632668, 713.3045280851129, 713.8144082538578, 715.2132654080469, 732.5751064382646, 12072.215306005082, 12396.938015310046, 12446.31545265001, 12748.553800616934, 12837.293948832717, 698.8567343694531, 705.3017512662427, 705.544923524324, 707.4729326651677, 715.2418436457372, 5695.288088988877, 5717.542596592346, 5731.570132240098, 5869.413058694096, 5900.793472228452, 113402.0, 114718.0, 115241.4237928805, 115967.42016290034, 117672.41163794126, 103326.0, 105866.0, 106013.46993265138, 106312.0, 107267.0, 85983.57008214919, 88784.0, 90436.0, 91152.44237788084, 94296.0, 12293.754124917272, 13042.478300867859, 13047.543335983195, 13208.339583021003, 13506.324683765575, 39254.6074539251, 40072.79963600163, 40561.391579126925, 41620.375694364004, 42639.0, 70650.64674676594, 74214.0, 75007.0, 76142.61928690321, 76490.23509764831, 18245.81754182494, 18407.0, 18482.537936551693, 18860.0, 18974.620507590047, 18758.812411875708, 19028.143733531746, 19573.119209635886, 19877.204911803365, 22315.10739570464, 17086.48740537786, 18641.254349825856, 19103.52241193981, 19191.32830350931, 19371.903140484206, 17594.38419655306, 17715.468535943426, 18904.810951890857, 19480.220791168194, 20560.28039018627, 17937.192826322585, 18057.548561286072, 19579.902100489406, 19723.506912327306, 19905.701414479085, 13367.465301387836, 13693.863061369259, 13823.79264311016, 14287.857121429068, 14387.424503019762, 14530.564083077525, 14744.189069601274, 14749.926250368679, 14806.185659788815, 14982.0], "value": 9404.039324905232, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1545.4000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 116.0, 106.0, 106.0, 109.0, 110.0, 110.0, 99.0, 108.0, 110.0], "value": 107.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 119.0, 145.0, 174.0, 120.0, 150.0, 145.0, 125.0, 177.0, 124.0], "value": 142.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 163.0, 168.0, 164.0, 166.0, 178.0, 167.0, 167.0, 174.0], "value": 167.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 76.0, 174.0, 77.0, 76.0, 76.0, 76.0, 155.0, 75.0], "value": 93.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 80.0, 135.0, 80.0, 79.0, 80.0, 131.0, 81.0, 79.0, 132.0], "value": 96.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 115.0, 114.0, 115.0, 114.0, 116.0, 116.0, 121.0, 121.0], "value": 115.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 152.0, 156.0, 157.0, 156.0, 152.0, 154.0, 153.0, 157.0, 151.0], "value": 154.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 102.0, 94.0, 94.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 54.0, 57.0, 56.0, 54.0, 53.0, 54.0, 54.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 60.0, 53.0, 54.0, 54.0, 54.0, 63.0, 55.0, 54.0, 57.0], "value": 55.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 83.0, 79.0, 78.0, 79.0, 84.0, 82.0, 81.0, 85.0, 79.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 136.0, 128.0, 127.0, 138.0, 131.0, 130.0, 132.0, 124.0, 160.0], "value": 133.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 177.0, 177.0, 181.0, 176.0, 179.0, 183.0, 179.0, 190.0, 185.0], "value": 180.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 67.0, 68.0, 66.0, 67.0, 72.0, 68.0, 67.0, 71.0], "value": 68.7, "unit": "score"}]}]} 04:11:12 INFO - Return code: 0 04:11:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:11:12 INFO - # TBPL SUCCESS # 04:11:12 INFO - Running post-action listener: _package_coverage_data 04:11:12 INFO - Running post-action listener: _resource_record_post_action 04:11:12 INFO - [mozharness: 2017-08-11 11:11:12.207324Z] Finished run-tests step (success) 04:11:12 INFO - Running post-run listener: _resource_record_post_run 04:11:12 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 16384; Write bytes: 430825472; Read time: 488; Write time: 389536 04:11:12 INFO - TinderboxPrint: CPU usage
13.2% 04:11:12 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 488 04:11:12 INFO - TinderboxPrint: I/O write bytes / time
430,825,472 / 389,536 04:11:12 INFO - TinderboxPrint: CPU idle
8,279.9 (86.8%) 04:11:12 INFO - TinderboxPrint: CPU user
1,204.7 (12.6%) 04:11:12 INFO - TinderboxPrint: Swap in / out
0 / 0 04:11:12 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 106332160; Read time: 28; Write time: 107048 04:11:12 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:11:12 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 8192; Write bytes: 324493312; Read time: 452; Write time: 282488 04:11:12 INFO - Running post-run listener: _upload_blobber_files 04:11:12 INFO - Blob upload gear active. 04:11:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:11:12 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:11:12 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:11:12 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:11:13 INFO - (blobuploader) - INFO - Open directory for files ... 04:11:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:11:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:11:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:11:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:11:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:11:13 INFO - (blobuploader) - INFO - Done attempting. 04:11:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:11:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:11:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:11:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:11:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:11:14 INFO - (blobuploader) - INFO - Done attempting. 04:11:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:11:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:11:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:11:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:11:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:11:15 INFO - (blobuploader) - INFO - Done attempting. 04:11:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:11:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:11:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:11:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:11:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:11:22 INFO - (blobuploader) - INFO - Done attempting. 04:11:22 INFO - (blobuploader) - INFO - Iteration through files over. 04:11:22 INFO - Return code: 0 04:11:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:11:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:11:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53dd2edea253415ff7a09efa44980934cafad5fb1e54f469287489852cf91031299f2711879ad91c12a17acbf27cbe5d03bdb52ee83e5a8572ef2499ea8f8605", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f754698c527786369081c5a900b082053b8379017cd8bf0eb0ecdfacba5aec61f440d6943127f75cc25fc6478b6573056df9139a1faf9c1e279408ec1f07822a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c2878695d48a647c1ef99fec15dfb2679f7fcdfcbcb499309aae5681aced87f9c06bbdf455fd52b89bb7ce140f8309b80951b403cec361792b58e76bfb4dab0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/184dd32bcfa9108bb24efca2a7c3ab6bf415e69b7def501d68b18a893c6a188e81f6bc9d62cb8403b6c7b1b1b4e89b93831ece4a65d65658f5152e72f09e9714"} 04:11:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:11:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:11:22 INFO - Contents: 04:11:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53dd2edea253415ff7a09efa44980934cafad5fb1e54f469287489852cf91031299f2711879ad91c12a17acbf27cbe5d03bdb52ee83e5a8572ef2499ea8f8605", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f754698c527786369081c5a900b082053b8379017cd8bf0eb0ecdfacba5aec61f440d6943127f75cc25fc6478b6573056df9139a1faf9c1e279408ec1f07822a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c2878695d48a647c1ef99fec15dfb2679f7fcdfcbcb499309aae5681aced87f9c06bbdf455fd52b89bb7ce140f8309b80951b403cec361792b58e76bfb4dab0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/184dd32bcfa9108bb24efca2a7c3ab6bf415e69b7def501d68b18a893c6a188e81f6bc9d62cb8403b6c7b1b1b4e89b93831ece4a65d65658f5152e72f09e9714"} 04:11:22 INFO - Running post-run listener: copy_logs_to_upload_dir 04:11:22 INFO - Copying logs to upload dir... 04:11:22 INFO - mkdir: /builds/slave/test/build/upload/logs 04:11:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.499495 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-11 04:11:21.766723) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-11 04:11:21.769792) ========= 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=1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53dd2edea253415ff7a09efa44980934cafad5fb1e54f469287489852cf91031299f2711879ad91c12a17acbf27cbe5d03bdb52ee83e5a8572ef2499ea8f8605", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f754698c527786369081c5a900b082053b8379017cd8bf0eb0ecdfacba5aec61f440d6943127f75cc25fc6478b6573056df9139a1faf9c1e279408ec1f07822a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c2878695d48a647c1ef99fec15dfb2679f7fcdfcbcb499309aae5681aced87f9c06bbdf455fd52b89bb7ce140f8309b80951b403cec361792b58e76bfb4dab0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/184dd32bcfa9108bb24efca2a7c3ab6bf415e69b7def501d68b18a893c6a188e81f6bc9d62cb8403b6c7b1b1b4e89b93831ece4a65d65658f5152e72f09e9714"} build_url:https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011504 build_url: 'https://queue.taskcluster.net/v1/task/DccvNO8hRT2EKCynlXqtsg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53dd2edea253415ff7a09efa44980934cafad5fb1e54f469287489852cf91031299f2711879ad91c12a17acbf27cbe5d03bdb52ee83e5a8572ef2499ea8f8605", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f754698c527786369081c5a900b082053b8379017cd8bf0eb0ecdfacba5aec61f440d6943127f75cc25fc6478b6573056df9139a1faf9c1e279408ec1f07822a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c2878695d48a647c1ef99fec15dfb2679f7fcdfcbcb499309aae5681aced87f9c06bbdf455fd52b89bb7ce140f8309b80951b403cec361792b58e76bfb4dab0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/184dd32bcfa9108bb24efca2a7c3ab6bf415e69b7def501d68b18a893c6a188e81f6bc9d62cb8403b6c7b1b1b4e89b93831ece4a65d65658f5152e72f09e9714"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-11 04:11:21.794441) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 04:11:21.794753) ========= 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=1258088b6da462a47ba2cd90000001a2-1502448623.974813-1210901481 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004478 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 04:11:21.879626) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-11 04:11:21.879960) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-11 04:11:21.880284) ========= ========= Total master_lag: 0.16 =========