builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-065 starttime: 1501084341.5 results: success (0) revision: 388d81ed93fa640f91d155f36254667c734157cf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-26 08:52:21.504138) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-26 08:52:21.504695) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-26 08:52:21.505034) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-26 08:52:21.519148) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-26 08:52:21.519516) ========= 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=523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-26 08:52:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 224M=0s 2017-07-26 08:52:22 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.188299 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-26 08:52:21.721137) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-26 08:52:21.721486) ========= 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=523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015479 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-26 08:52:21.771735) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-26 08:52:21.772161) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 388d81ed93fa640f91d155f36254667c734157cf --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 388d81ed93fa640f91d155f36254667c734157cf --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=523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-26 08:52:22,823 truncating revision to first 12 chars 2017-07-26 08:52:22,823 Setting DEBUG logging. 2017-07-26 08:52:22,823 attempt 1/10 2017-07-26 08:52:22,823 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/388d81ed93fa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-26 08:52:23,119 unpacking tar archive at: mozilla-central-388d81ed93fa/testing/mozharness/ program finished with exit code 0 elapsedTime=0.662895 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-26 08:52:22.449723) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-26 08:52:22.450202) ========= script_repo_revision: 388d81ed93fa640f91d155f36254667c734157cf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-26 08:52:22.450911) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-26 08:52:22.451698) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-26 08:52:22.462177) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-07-26 08:52:22.462550) ========= /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=523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:52:23 INFO - MultiFileLogger online at 20170726 08:52:23 in /builds/slave/test 08:52:23 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 08:52:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:52:23 INFO - {'append_to_log': False, 08:52:23 INFO - 'base_work_dir': '/builds/slave/test', 08:52:23 INFO - 'blob_upload_branch': 'Firefox', 08:52:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:52:23 INFO - 'branch': 'Firefox', 08:52:23 INFO - 'buildbot_json_path': 'buildprops.json', 08:52:23 INFO - 'code_coverage': False, 08:52:23 INFO - 'config_files': ('talos/linux_config.py',), 08:52:23 INFO - 'default_actions': ('clobber', 08:52:23 INFO - 'read-buildbot-config', 08:52:23 INFO - 'download-and-extract', 08:52:23 INFO - 'populate-webroot', 08:52:23 INFO - 'create-virtualenv', 08:52:23 INFO - 'install', 08:52:23 INFO - 'setup-mitmproxy', 08:52:23 INFO - 'run-tests'), 08:52:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:52:23 INFO - 'disable_ccov_upload': False, 08:52:23 INFO - 'download_minidump_stackwalk': True, 08:52:23 INFO - 'download_symbols': 'ondemand', 08:52:23 INFO - 'enable_stylo': False, 08:52:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:52:23 INFO - 'tooltool.py': '/tools/tooltool.py', 08:52:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:52:23 INFO - '/tools/misc-python/virtualenv.py')}, 08:52:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:52:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:52:23 INFO - 'gecko_profile': False, 08:52:23 INFO - 'gecko_profile_interval': 0, 08:52:23 INFO - 'installer_path': 'installer.exe', 08:52:23 INFO - 'log_level': 'info', 08:52:23 INFO - 'log_name': 'talos', 08:52:23 INFO - 'log_to_console': True, 08:52:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:52:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:52:23 INFO - 'opt_config_files': (), 08:52:23 INFO - 'pip_index': False, 08:52:23 INFO - 'suite': 'dromaeojs-e10s', 08:52:23 INFO - 'system_bits': '32', 08:52:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:52:23 INFO - 'title': 'talos-linux64-ix-065', 08:52:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:52:23 INFO - 'use_talos_json': True, 08:52:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:52:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:52:23 INFO - 'work_dir': 'build'} 08:52:23 INFO - [mozharness: 2017-07-26 15:52:23.619805Z] Running clobber step. 08:52:23 INFO - Running pre-action listener: _resource_record_pre_action 08:52:23 INFO - Running main action method: clobber 08:52:23 INFO - rmtree: /builds/slave/test/build 08:52:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:52:24 INFO - Running post-action listener: _resource_record_post_action 08:52:24 INFO - [mozharness: 2017-07-26 15:52:24.656749Z] Finished clobber step (success) 08:52:24 INFO - [mozharness: 2017-07-26 15:52:24.656852Z] Running read-buildbot-config step. 08:52:24 INFO - Running pre-action listener: _resource_record_pre_action 08:52:24 INFO - Running main action method: read_buildbot_config 08:52:24 INFO - Using buildbot properties: 08:52:24 INFO - { 08:52:24 INFO - "project": "", 08:52:24 INFO - "product": "firefox", 08:52:24 INFO - "who": "Callek@gmail.com", 08:52:24 INFO - "installer_path": "public/build/target.tar.bz2", 08:52:24 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 08:52:24 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 08:52:24 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 08:52:24 INFO - "stage_platform": "linux64-stylosequential", 08:52:24 INFO - "basedir": "/builds/slave/test", 08:52:24 INFO - "buildnumber": 151, 08:52:24 INFO - "platform": "ubuntu64_hw_styloseq", 08:52:24 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:52:24 INFO - "slavebuilddir": "test", 08:52:24 INFO - "taskId": "KAWDvvFMSI-3pnysxz3xHg", 08:52:24 INFO - "branch": "mozilla-central", 08:52:24 INFO - "script_repo_revision": "production", 08:52:24 INFO - "revision": "388d81ed93fa640f91d155f36254667c734157cf", 08:52:24 INFO - "slavename": "talos-linux64-ix-065", 08:52:24 INFO - "repo_path": "mozilla-central" 08:52:24 INFO - } 08:52:24 INFO - Finding installer, test and symbols from parent task. 08:52:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KAWDvvFMSI-3pnysxz3xHg'}, attempt #1 08:52:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KAWDvvFMSI-3pnysxz3xHg'}, attempt #1 08:52:25 INFO - Task dependencies: McIpE5nYSU6x4xohkK7ZTQ 08:52:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ'}, attempt #1 08:52:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.tar.bz2 08:52:25 INFO - Set test_url: https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/test_packages.json 08:52:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.crashreporter-symbols.zip 08:52:25 INFO - Running post-action listener: _resource_record_post_action 08:52:25 INFO - [mozharness: 2017-07-26 15:52:25.999050Z] Finished read-buildbot-config step (success) 08:52:25 INFO - [mozharness: 2017-07-26 15:52:25.999271Z] Running download-and-extract step. 08:52:25 INFO - Running pre-action listener: _resource_record_pre_action 08:52:25 INFO - Running main action method: download_and_extract 08:52:25 INFO - mkdir: /builds/slave/test/build/tests 08:52:26 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')]} 08:52:26 INFO - trying https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.test_packages.json 08:52:26 INFO - Downloading https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:52:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:52:26 INFO - Downloaded 989 bytes. 08:52:26 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:52:26 INFO - Using the following test package requirements: 08:52:26 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:52:26 INFO - u'common': [u'target.common.tests.zip'], 08:52:26 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:52:26 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:52:26 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:52:26 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:52:26 INFO - u'mozbase': [u'target.common.tests.zip'], 08:52:26 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:52:26 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:52:26 INFO - u'web-platform': [u'target.common.tests.zip', 08:52:26 INFO - u'target.web-platform.tests.tar.gz'], 08:52:26 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:52:26 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:52:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.common.tests.zip 08:52:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:52:26 INFO - Fetch https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.common.tests.zip into memory 08:52:28 INFO - Content-Length response header: 72522421 08:52:28 INFO - Bytes received: 72522421 08:52:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:52:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.common.tests.zip 08:52:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:52:39 INFO - Fetch https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.common.tests.zip into memory 08:52:40 INFO - Content-Length response header: 72522421 08:52:40 INFO - Bytes received: 72522421 08:52:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.talos.tests.zip 08:52:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:52:52 INFO - Fetch https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.talos.tests.zip into memory 08:52:53 INFO - Content-Length response header: 14496448 08:52:53 INFO - Bytes received: 14496448 08:52:53 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')]} 08:52:53 INFO - trying https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.tar.bz2 08:52:53 INFO - Downloading https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:52:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:52:55 INFO - Downloaded 62051695 bytes. 08:52:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.tar.bz2 08:52:55 INFO - mkdir: /builds/slave/test/properties 08:52:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:52:55 INFO - Writing to file /builds/slave/test/properties/build_url 08:52:55 INFO - Contents: 08:52:55 INFO - build_url:https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.tar.bz2 08:52:55 INFO - Running post-action listener: _resource_record_post_action 08:52:55 INFO - Running post-action listener: set_extra_try_arguments 08:52:55 INFO - [mozharness: 2017-07-26 15:52:55.462953Z] Finished download-and-extract step (success) 08:52:55 INFO - [mozharness: 2017-07-26 15:52:55.463161Z] Running populate-webroot step. 08:52:55 INFO - Running pre-action listener: _resource_record_pre_action 08:52:55 INFO - Running main action method: populate_webroot 08:52:55 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:52:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:52:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:52:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:52:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:52:55 INFO - 'tests': ['damp', 'tps']}, 08:52:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:52:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:52:55 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:52:55 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:52:55 INFO - 'other-e10s': {'tests': ['a11yr', 08:52:55 INFO - 'ts_paint', 08:52:55 INFO - 'tpaint', 08:52:55 INFO - 'sessionrestore', 08:52:55 INFO - 'sessionrestore_many_windows', 08:52:55 INFO - 'sessionrestore_no_auto_restore', 08:52:55 INFO - 'tabpaint']}, 08:52:55 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 08:52:55 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 08:52:55 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:52:55 INFO - 'talos_options': ['--mitmproxy', 08:52:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:52:55 INFO - '--firstNonBlankPaint'], 08:52:55 INFO - 'tests': ['quantum_pageload_google', 08:52:55 INFO - 'quantum_pageload_youtube', 08:52:55 INFO - 'quantum_pageload_amazon', 08:52:55 INFO - 'quantum_pageload_facebook']}, 08:52:55 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:52:55 INFO - 'talos_options': ['--stylo', 08:52:55 INFO - '--mitmproxy', 08:52:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:52:55 INFO - '--firstNonBlankPaint'], 08:52:55 INFO - 'tests': ['quantum_pageload_google', 08:52:55 INFO - 'quantum_pageload_youtube', 08:52:55 INFO - 'quantum_pageload_amazon', 08:52:55 INFO - 'quantum_pageload_facebook']}, 08:52:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:52:55 INFO - 'tsvgr_opacity', 08:52:55 INFO - 'tart', 08:52:55 INFO - 'tscrollx', 08:52:55 INFO - 'cart', 08:52:55 INFO - 'tsvg_static']}, 08:52:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:52:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:52:55 INFO - 'talos_options': ['--xperf_path', 08:52:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:52:55 INFO - 'tests': ['tp5n']}}} 08:52:55 INFO - Running post-action listener: _resource_record_post_action 08:52:55 INFO - [mozharness: 2017-07-26 15:52:55.478544Z] Finished populate-webroot step (success) 08:52:55 INFO - [mozharness: 2017-07-26 15:52:55.478635Z] Running create-virtualenv step. 08:52:55 INFO - Running pre-action listener: _resource_record_pre_action 08:52:55 INFO - Running main action method: create_virtualenv 08:52:55 INFO - Creating virtualenv /builds/slave/test/build/venv 08:52: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 08:52:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:52:55 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 08:52:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:52:55 INFO - Using real prefix '/usr' 08:52:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:52:56 INFO - Installing distribute.............................................................................................................................................................................................done. 08:52:58 INFO - Installing pip.................done. 08:52:58 INFO - Return code: 0 08:52:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:52:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:52:58 INFO - Reading from file tmpfile_stdout 08:52:58 INFO - Output received: 08:52: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) 08:52:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:52: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')]} 08:52:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52: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')]} 08:52:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52: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 0x17c81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1994a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b37d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b34eb0>, '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 0x1b35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b36240>, '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': '523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280', '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 08:52: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 08:52: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 08:52:58 INFO - Using env: {'DISPLAY': ':0', 08:52:58 INFO - 'HOME': '/home/cltbld', 08:52:58 INFO - 'LANG': 'en_US.UTF-8', 08:52:58 INFO - 'LANGUAGE': 'en_US:en', 08:52:58 INFO - 'LOGNAME': 'cltbld', 08:52:58 INFO - 'MAIL': '/var/mail/cltbld', 08:52:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:58 INFO - 'MOZ_NO_REMOTE': '1', 08:52:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:58 INFO - 'NO_EM_RESTART': '1', 08:52:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:58 INFO - 'PWD': '/builds/slave/test', 08:52:58 INFO - 'SHELL': '/bin/bash', 08:52:58 INFO - 'SHLVL': '1', 08:52:58 INFO - 'TERM': 'linux', 08:52:58 INFO - 'TMOUT': '86400', 08:52:58 INFO - 'USER': 'cltbld', 08:52:58 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280', 08:52:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:58 INFO - '_': '/tools/buildbot/bin/python'} 08:52:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52: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 08:52:58 INFO - Cleaning up... 08:52:58 INFO - Return code: 0 08:52:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:52: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')]} 08:52:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52: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')]} 08:52:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52: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 0x17c81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1994a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b37d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b34eb0>, '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 0x1b35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b36240>, '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': '523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280', '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 08:52: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 08:52: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 08:52:58 INFO - Using env: {'DISPLAY': ':0', 08:52:58 INFO - 'HOME': '/home/cltbld', 08:52:58 INFO - 'LANG': 'en_US.UTF-8', 08:52:58 INFO - 'LANGUAGE': 'en_US:en', 08:52:58 INFO - 'LOGNAME': 'cltbld', 08:52:58 INFO - 'MAIL': '/var/mail/cltbld', 08:52:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:58 INFO - 'MOZ_NO_REMOTE': '1', 08:52:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:58 INFO - 'NO_EM_RESTART': '1', 08:52:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:58 INFO - 'PWD': '/builds/slave/test', 08:52:58 INFO - 'SHELL': '/bin/bash', 08:52:58 INFO - 'SHLVL': '1', 08:52:58 INFO - 'TERM': 'linux', 08:52:58 INFO - 'TMOUT': '86400', 08:52:58 INFO - 'USER': 'cltbld', 08:52:58 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280', 08:52:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:58 INFO - '_': '/tools/buildbot/bin/python'} 08:52:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:58 INFO - Downloading/unpacking psutil>=3.1.1 08:52: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 08:52: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 08:52: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 08:52: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 08:52:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:52:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:52:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:52:59 INFO - Installing collected packages: psutil 08:52:59 INFO - Running setup.py install for psutil 08:52:59 INFO - building 'psutil._psutil_linux' extension 08:52:59 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 08:53: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 08:53:00 INFO - building 'psutil._psutil_posix' extension 08:53: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 08:53: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 08:53:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:53:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:53:00 INFO - Successfully installed psutil 08:53:00 INFO - Cleaning up... 08:53:00 INFO - Return code: 0 08:53:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:53: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')]} 08:53:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53: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')]} 08:53:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53: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 0x17c81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1994a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b37d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b34eb0>, '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 0x1b35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b36240>, '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': '523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280', '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 08:53: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 08:53: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 08:53:00 INFO - Using env: {'DISPLAY': ':0', 08:53:00 INFO - 'HOME': '/home/cltbld', 08:53:00 INFO - 'LANG': 'en_US.UTF-8', 08:53:00 INFO - 'LANGUAGE': 'en_US:en', 08:53:00 INFO - 'LOGNAME': 'cltbld', 08:53:00 INFO - 'MAIL': '/var/mail/cltbld', 08:53:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:00 INFO - 'MOZ_NO_REMOTE': '1', 08:53:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:00 INFO - 'NO_EM_RESTART': '1', 08:53:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:00 INFO - 'PWD': '/builds/slave/test', 08:53:00 INFO - 'SHELL': '/bin/bash', 08:53:00 INFO - 'SHLVL': '1', 08:53:00 INFO - 'TERM': 'linux', 08:53:00 INFO - 'TMOUT': '86400', 08:53:00 INFO - 'USER': 'cltbld', 08:53:00 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280', 08:53:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:00 INFO - '_': '/tools/buildbot/bin/python'} 08:53:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:53: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 08:53: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 08:53: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 08:53: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 08:53:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:53:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:53:01 INFO - Installing collected packages: mozsystemmonitor 08:53:01 INFO - Running setup.py install for mozsystemmonitor 08:53:01 INFO - Successfully installed mozsystemmonitor 08:53:01 INFO - Cleaning up... 08:53:01 INFO - Return code: 0 08:53:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:53:01 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')]} 08:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:01 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')]} 08:53:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:01 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 0x17c81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1994a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b37d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b34eb0>, '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 0x1b35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b36240>, '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': '523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280', '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 08:53:01 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 08:53:01 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 08:53:01 INFO - Using env: {'DISPLAY': ':0', 08:53:01 INFO - 'HOME': '/home/cltbld', 08:53:01 INFO - 'LANG': 'en_US.UTF-8', 08:53:01 INFO - 'LANGUAGE': 'en_US:en', 08:53:01 INFO - 'LOGNAME': 'cltbld', 08:53:01 INFO - 'MAIL': '/var/mail/cltbld', 08:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:01 INFO - 'MOZ_NO_REMOTE': '1', 08:53:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:01 INFO - 'NO_EM_RESTART': '1', 08:53:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:01 INFO - 'PWD': '/builds/slave/test', 08:53:01 INFO - 'SHELL': '/bin/bash', 08:53:01 INFO - 'SHLVL': '1', 08:53:01 INFO - 'TERM': 'linux', 08:53:01 INFO - 'TMOUT': '86400', 08:53:01 INFO - 'USER': 'cltbld', 08:53:01 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280', 08:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:01 INFO - '_': '/tools/buildbot/bin/python'} 08:53:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:02 INFO - Downloading/unpacking jsonschema==2.5.1 08:53: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 08:53: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 08:53: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 08:53: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 08:53:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:53:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:53: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 08:53: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 08:53: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 08:53: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 08:53:03 INFO - Downloading functools32-3.2.3-2.tar.gz 08:53:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:53:03 INFO - warning: no files found matching '*.txt' 08:53:03 INFO - no previously-included directories found matching 'build' 08:53:03 INFO - no previously-included directories found matching 'dist' 08:53:03 INFO - no previously-included directories found matching '.git*' 08:53:03 INFO - Installing collected packages: jsonschema, functools32 08:53:03 INFO - Running setup.py install for functools32 08:53:03 INFO - warning: no files found matching '*.txt' 08:53:03 INFO - no previously-included directories found matching 'build' 08:53:03 INFO - no previously-included directories found matching 'dist' 08:53:03 INFO - no previously-included directories found matching '.git*' 08:53:03 INFO - Successfully installed jsonschema functools32 08:53:03 INFO - Cleaning up... 08:53:03 INFO - Return code: 0 08:53:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:53:03 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')]} 08:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:03 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')]} 08:53:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x17c81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1994a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b37d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b34eb0>, '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 0x1b35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b36240>, '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': '523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280', '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 08:53:03 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 08:53:03 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 08:53:03 INFO - Using env: {'DISPLAY': ':0', 08:53:03 INFO - 'HOME': '/home/cltbld', 08:53:03 INFO - 'LANG': 'en_US.UTF-8', 08:53:03 INFO - 'LANGUAGE': 'en_US:en', 08:53:03 INFO - 'LOGNAME': 'cltbld', 08:53:03 INFO - 'MAIL': '/var/mail/cltbld', 08:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:03 INFO - 'MOZ_NO_REMOTE': '1', 08:53:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:03 INFO - 'NO_EM_RESTART': '1', 08:53:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:03 INFO - 'PWD': '/builds/slave/test', 08:53:03 INFO - 'SHELL': '/bin/bash', 08:53:03 INFO - 'SHLVL': '1', 08:53:03 INFO - 'TERM': 'linux', 08:53:03 INFO - 'TMOUT': '86400', 08:53:03 INFO - 'USER': 'cltbld', 08:53:03 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280', 08:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:03 INFO - '_': '/tools/buildbot/bin/python'} 08:53:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:53:03 INFO - Cleaning up... 08:53:03 INFO - Return code: 0 08:53:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:53:03 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')]} 08:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:03 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')]} 08:53:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x17c81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1994a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b37d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b34eb0>, '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 0x1b35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b36240>, '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': '523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280', '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 08:53:03 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 08:53:03 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 08:53:03 INFO - Using env: {'DISPLAY': ':0', 08:53:03 INFO - 'HOME': '/home/cltbld', 08:53:03 INFO - 'LANG': 'en_US.UTF-8', 08:53:03 INFO - 'LANGUAGE': 'en_US:en', 08:53:03 INFO - 'LOGNAME': 'cltbld', 08:53:03 INFO - 'MAIL': '/var/mail/cltbld', 08:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:03 INFO - 'MOZ_NO_REMOTE': '1', 08:53:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:03 INFO - 'NO_EM_RESTART': '1', 08:53:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:03 INFO - 'PWD': '/builds/slave/test', 08:53:03 INFO - 'SHELL': '/bin/bash', 08:53:03 INFO - 'SHLVL': '1', 08:53:03 INFO - 'TERM': 'linux', 08:53:03 INFO - 'TMOUT': '86400', 08:53:03 INFO - 'USER': 'cltbld', 08:53:03 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280', 08:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:03 INFO - '_': '/tools/buildbot/bin/python'} 08:53:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:04 INFO - Downloading/unpacking blobuploader==1.2.4 08:53: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 08:53: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 08:53: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 08:53: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 08:53:05 INFO - Downloading blobuploader-1.2.4.tar.gz 08:53:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:53:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:53: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 08:53: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 08:53: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 08:53: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 08:53:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:53:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:53: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 08:53: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 08:53: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 08:53: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 08:53:05 INFO - Downloading docopt-0.6.1.tar.gz 08:53:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:53:05 INFO - Installing collected packages: blobuploader, requests, docopt 08:53:05 INFO - Running setup.py install for blobuploader 08:53:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:53:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:53:05 INFO - Running setup.py install for requests 08:53:06 INFO - Running setup.py install for docopt 08:53:06 INFO - Successfully installed blobuploader requests docopt 08:53:06 INFO - Cleaning up... 08:53:06 INFO - Return code: 0 08:53:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53: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')]} 08:53:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53: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')]} 08:53:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53: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 0x17c81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1994a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b37d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b34eb0>, '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 0x1b35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b36240>, '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': '523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280', '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 08:53: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 08:53: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 08:53:06 INFO - Using env: {'DISPLAY': ':0', 08:53:06 INFO - 'HOME': '/home/cltbld', 08:53:06 INFO - 'LANG': 'en_US.UTF-8', 08:53:06 INFO - 'LANGUAGE': 'en_US:en', 08:53:06 INFO - 'LOGNAME': 'cltbld', 08:53:06 INFO - 'MAIL': '/var/mail/cltbld', 08:53:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:06 INFO - 'MOZ_NO_REMOTE': '1', 08:53:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:06 INFO - 'NO_EM_RESTART': '1', 08:53:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:06 INFO - 'PWD': '/builds/slave/test', 08:53:06 INFO - 'SHELL': '/bin/bash', 08:53:06 INFO - 'SHLVL': '1', 08:53:06 INFO - 'TERM': 'linux', 08:53:06 INFO - 'TMOUT': '86400', 08:53:06 INFO - 'USER': 'cltbld', 08:53:06 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280', 08:53:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:06 INFO - '_': '/tools/buildbot/bin/python'} 08:53:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:53:06 INFO - Running setup.py (path:/tmp/pip-3oWvaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:53:06 INFO - Running setup.py (path:/tmp/pip-bu5b0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:53:06 INFO - Running setup.py (path:/tmp/pip-SRBBo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:53:06 INFO - Running setup.py (path:/tmp/pip-Bs5QhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:53:06 INFO - Running setup.py (path:/tmp/pip-df2TEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:53:06 INFO - Running setup.py (path:/tmp/pip-WhL_Qn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:53:06 INFO - Running setup.py (path:/tmp/pip-ZahRew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:53:06 INFO - Running setup.py (path:/tmp/pip-EOytWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:53:07 INFO - Running setup.py (path:/tmp/pip-vI6fhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:53:07 INFO - Running setup.py (path:/tmp/pip-KzwEKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:53:07 INFO - Running setup.py (path:/tmp/pip-AOY7Yt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:53:07 INFO - Running setup.py (path:/tmp/pip-Q3_48m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:53:07 INFO - Running setup.py (path:/tmp/pip-0yxAVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:53:07 INFO - Running setup.py (path:/tmp/pip-wa7jmo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:07 INFO - Running setup.py (path:/tmp/pip-QK2IJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:53:07 INFO - Running setup.py (path:/tmp/pip-ZXe0Wo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:53:07 INFO - Running setup.py (path:/tmp/pip-AjamU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:53:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:53:07 INFO - Running setup.py install for manifestparser 08:53:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:53:07 INFO - Running setup.py install for mozcrash 08:53:07 INFO - Running setup.py install for mozdebug 08:53:08 INFO - Running setup.py install for mozdevice 08:53:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:53:08 INFO - Running setup.py install for mozfile 08:53:08 INFO - Running setup.py install for mozhttpd 08:53:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:53:08 INFO - Running setup.py install for mozinfo 08:53:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:53:08 INFO - Running setup.py install for mozInstall 08:53:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:53:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:53:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:53:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:53:08 INFO - Running setup.py install for mozleak 08:53:08 INFO - Running setup.py install for mozlog 08:53:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:53:08 INFO - Running setup.py install for moznetwork 08:53:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:53:08 INFO - Running setup.py install for mozprocess 08:53:08 INFO - Running setup.py install for mozprofile 08:53:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:53:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:53:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:53:09 INFO - Running setup.py install for mozrunner 08:53:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:53:09 INFO - Running setup.py install for mozscreenshot 08:53:09 INFO - Running setup.py install for moztest 08:53:09 INFO - Running setup.py install for mozversion 08:53:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:53:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:53:09 INFO - Cleaning up... 08:53:09 INFO - Return code: 0 08:53:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53:09 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')]} 08:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:09 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')]} 08:53:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:09 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 0x17c81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1994a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b37d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b34eb0>, '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 0x1b35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b36240>, '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': '523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280', '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 08:53:09 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 08:53:09 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 08:53:09 INFO - Using env: {'DISPLAY': ':0', 08:53:09 INFO - 'HOME': '/home/cltbld', 08:53:09 INFO - 'LANG': 'en_US.UTF-8', 08:53:09 INFO - 'LANGUAGE': 'en_US:en', 08:53:09 INFO - 'LOGNAME': 'cltbld', 08:53:09 INFO - 'MAIL': '/var/mail/cltbld', 08:53:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:09 INFO - 'MOZ_NO_REMOTE': '1', 08:53:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:09 INFO - 'NO_EM_RESTART': '1', 08:53:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:09 INFO - 'PWD': '/builds/slave/test', 08:53:09 INFO - 'SHELL': '/bin/bash', 08:53:09 INFO - 'SHLVL': '1', 08:53:09 INFO - 'TERM': 'linux', 08:53:09 INFO - 'TMOUT': '86400', 08:53:09 INFO - 'USER': 'cltbld', 08:53:09 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280', 08:53:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:09 INFO - '_': '/tools/buildbot/bin/python'} 08:53:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:53:09 INFO - Running setup.py (path:/tmp/pip-XSa5bI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:53:09 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)) 08:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:53:09 INFO - Running setup.py (path:/tmp/pip-Sh9KQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:53:09 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)) 08:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:53:09 INFO - Running setup.py (path:/tmp/pip-Q6J_yQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:53:09 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)) 08:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:53:09 INFO - Running setup.py (path:/tmp/pip-HViWru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:53: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)) 08:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:53:10 INFO - Running setup.py (path:/tmp/pip-dlhmAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:53: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)) 08:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:53:10 INFO - Running setup.py (path:/tmp/pip-Wlf9EL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:53: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)) 08:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:53:10 INFO - Running setup.py (path:/tmp/pip-02zO5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:53: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)) 08:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:53:10 INFO - Running setup.py (path:/tmp/pip-lrbOeB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:53: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)) 08:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:53:10 INFO - Running setup.py (path:/tmp/pip-NtYXIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:53: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)) 08:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:53:10 INFO - Running setup.py (path:/tmp/pip-C6pKKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:53: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)) 08:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:53:10 INFO - Running setup.py (path:/tmp/pip-PU79fx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:53:10 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)) 08:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:53:10 INFO - Running setup.py (path:/tmp/pip-Ot_znv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:53:10 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)) 08:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:53:10 INFO - Running setup.py (path:/tmp/pip-lmVMXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:53:10 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)) 08:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:53:10 INFO - Running setup.py (path:/tmp/pip-a4u07e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:53:10 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)) 08:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:10 INFO - Running setup.py (path:/tmp/pip-P_uoTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:10 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)) 08:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:53:10 INFO - Running setup.py (path:/tmp/pip-5RSYrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:53:10 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)) 08:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:53:10 INFO - Running setup.py (path:/tmp/pip-N3lpbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:53:10 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)) 08:53:10 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)) 08:53:10 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)) 08:53:10 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)) 08:53:10 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)) 08:53:10 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)) 08:53:10 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)) 08:53:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:53:10 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 08:53:10 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 08:53:10 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 08:53:10 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 08:53:12 INFO - Downloading blessings-1.6.tar.gz 08:53:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:53:12 INFO - Installing collected packages: blessings 08:53:12 INFO - Running setup.py install for blessings 08:53:12 INFO - Successfully installed blessings 08:53:12 INFO - Cleaning up... 08:53:12 INFO - Return code: 0 08:53:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:53:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:53:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:53:12 INFO - Reading from file tmpfile_stdout 08:53:12 INFO - Current package versions: 08:53:12 INFO - argparse == 1.2.1 08:53:12 INFO - blessings == 1.6 08:53:12 INFO - blobuploader == 1.2.4 08:53:12 INFO - docopt == 0.6.1 08:53:12 INFO - functools32 == 3.2.3-2 08:53:12 INFO - jsonschema == 2.5.1 08:53:12 INFO - manifestparser == 1.1 08:53:12 INFO - mozInstall == 1.13 08:53:12 INFO - mozcrash == 1.0 08:53:12 INFO - mozdebug == 0.1 08:53:12 INFO - mozdevice == 0.51 08:53:12 INFO - mozfile == 1.2 08:53:12 INFO - mozhttpd == 0.7 08:53:12 INFO - mozinfo == 0.10 08:53:12 INFO - mozleak == 0.1 08:53:12 INFO - mozlog == 3.5 08:53:12 INFO - moznetwork == 0.27 08:53:12 INFO - mozprocess == 0.25 08:53:12 INFO - mozprofile == 0.28 08:53:12 INFO - mozrunner == 6.13 08:53:12 INFO - mozscreenshot == 0.1 08:53:12 INFO - mozsystemmonitor == 0.3 08:53:12 INFO - moztest == 0.8 08:53:12 INFO - mozversion == 1.4 08:53:12 INFO - psutil == 3.1.1 08:53:12 INFO - requests == 1.2.3 08:53:12 INFO - wsgiref == 0.1.2 08:53:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53:12 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')]} 08:53:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:12 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')]} 08:53:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:12 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 0x17c81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1994a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b37d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b34eb0>, '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 0x1b35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b36240>, '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': '523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280', '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 08:53:12 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 08:53:12 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 08:53:12 INFO - Using env: {'DISPLAY': ':0', 08:53:12 INFO - 'HOME': '/home/cltbld', 08:53:12 INFO - 'LANG': 'en_US.UTF-8', 08:53:12 INFO - 'LANGUAGE': 'en_US:en', 08:53:12 INFO - 'LOGNAME': 'cltbld', 08:53:12 INFO - 'MAIL': '/var/mail/cltbld', 08:53:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:12 INFO - 'MOZ_NO_REMOTE': '1', 08:53:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:12 INFO - 'NO_EM_RESTART': '1', 08:53:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:12 INFO - 'PWD': '/builds/slave/test', 08:53:12 INFO - 'SHELL': '/bin/bash', 08:53:12 INFO - 'SHLVL': '1', 08:53:12 INFO - 'TERM': 'linux', 08:53:12 INFO - 'TMOUT': '86400', 08:53:12 INFO - 'USER': 'cltbld', 08:53:12 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280', 08:53:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:12 INFO - '_': '/tools/buildbot/bin/python'} 08:53:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:12 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)) 08:53:12 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)) 08:53:12 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)) 08:53:12 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)) 08:53:12 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)) 08:53:12 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)) 08:53:12 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)) 08:53:12 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)) 08:53:12 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)) 08:53:12 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:53:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:13 INFO - Downloading marionette_driver-1.4.0.tar.gz 08:53:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 08:53:13 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 08:53: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 08:53: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 08:53: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 08:53: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 08:53:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 08:53: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)) 08:53: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)) 08:53:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:53:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:53:14 INFO - Installing collected packages: marionette-driver, simplejson 08:53:14 INFO - Running setup.py install for marionette-driver 08:53:14 INFO - Running setup.py install for simplejson 08:53:14 INFO - building 'simplejson._speedups' extension 08:53: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 08:53: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 08:53:15 INFO - Successfully installed marionette-driver simplejson 08:53:15 INFO - Cleaning up... 08:53:15 INFO - Return code: 0 08:53:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:53: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')]} 08:53:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53: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')]} 08:53:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53: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 0x17c81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1994a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b37d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b34eb0>, '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 0x1b35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b36240>, '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': '523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280', '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 08:53: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 08:53: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 08:53:15 INFO - Using env: {'DISPLAY': ':0', 08:53:15 INFO - 'HOME': '/home/cltbld', 08:53:15 INFO - 'LANG': 'en_US.UTF-8', 08:53:15 INFO - 'LANGUAGE': 'en_US:en', 08:53:15 INFO - 'LOGNAME': 'cltbld', 08:53:15 INFO - 'MAIL': '/var/mail/cltbld', 08:53:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:15 INFO - 'MOZ_NO_REMOTE': '1', 08:53:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:15 INFO - 'NO_EM_RESTART': '1', 08:53:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:15 INFO - 'PWD': '/builds/slave/test', 08:53:15 INFO - 'SHELL': '/bin/bash', 08:53:15 INFO - 'SHLVL': '1', 08:53:15 INFO - 'TERM': 'linux', 08:53:15 INFO - 'TMOUT': '86400', 08:53:15 INFO - 'USER': 'cltbld', 08:53:15 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280', 08:53:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:15 INFO - '_': '/tools/buildbot/bin/python'} 08:53:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:53:15 INFO - Cleaning up... 08:53:15 INFO - Return code: 0 08:53:15 INFO - Running post-action listener: _resource_record_post_action 08:53:15 INFO - Running post-action listener: _start_resource_monitoring 08:53:15 INFO - Starting resource monitoring. 08:53:15 INFO - [mozharness: 2017-07-26 15:53:15.568436Z] Finished create-virtualenv step (success) 08:53:15 INFO - [mozharness: 2017-07-26 15:53:15.568902Z] Running install step. 08:53:15 INFO - Running pre-action listener: _resource_record_pre_action 08:53:15 INFO - Running main action method: install 08:53:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:53:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:53:15 INFO - Reading from file tmpfile_stdout 08:53:15 INFO - Detecting whether we're running mozinstall >=1.0... 08:53:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:53:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:53:15 INFO - Reading from file tmpfile_stdout 08:53:15 INFO - Output received: 08:53:15 INFO - Usage: mozinstall [options] installer 08:53:15 INFO - Options: 08:53:15 INFO - -h, --help show this help message and exit 08:53:15 INFO - -d DEST, --destination=DEST 08:53:15 INFO - Directory to install application into. [default: 08:53:15 INFO - "/builds/slave/test"] 08:53:15 INFO - --app=APP Application being installed. [default: firefox] 08:53:15 INFO - mkdir: /builds/slave/test/build/application 08:53:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:53:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:53:33 INFO - Reading from file tmpfile_stdout 08:53:33 INFO - Output received: 08:53:33 INFO - /builds/slave/test/build/application/firefox/firefox 08:53:33 INFO - Running post-action listener: _resource_record_post_action 08:53:33 INFO - [mozharness: 2017-07-26 15:53:33.953267Z] Finished install step (success) 08:53:33 INFO - [mozharness: 2017-07-26 15:53:33.953535Z] Running setup-mitmproxy step. 08:53:33 INFO - Running pre-action listener: _resource_record_pre_action 08:53:33 INFO - Running main action method: setup_mitmproxy 08:53:33 INFO - Skipping: mitmproxy is not required 08:53:33 INFO - Running post-action listener: _resource_record_post_action 08:53:33 INFO - [mozharness: 2017-07-26 15:53:33.954678Z] Finished setup-mitmproxy step (success) 08:53:33 INFO - [mozharness: 2017-07-26 15:53:33.954901Z] Running run-tests step. 08:53:33 INFO - Running pre-action listener: _resource_record_pre_action 08:53:33 INFO - Running pre-action listener: _set_gcov_prefix 08:53:33 INFO - Running main action method: run_tests 08:53:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:53:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:53:33 INFO - Python 2.7.3 08:53:33 INFO - Return code: 0 08:53:33 INFO - grabbing minidump binary from tooltool 08:53:33 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')]} 08:53:33 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 0x1b34eb0>, '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 0x1b35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b36240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:53:33 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 08:53:33 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 08:53:33 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 08:53:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:53:34 INFO - Return code: 0 08:53:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:53:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:53:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:53:34 INFO - ENV: RUST_BACKTRACE is now 1 08:53:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:53:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:53: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/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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 08:53: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/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-065 --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 08:53:34 INFO - Using env: {'DISPLAY': ':0', 08:53:34 INFO - 'HOME': '/home/cltbld', 08:53:34 INFO - 'LANG': 'en_US.UTF-8', 08:53:34 INFO - 'LANGUAGE': 'en_US:en', 08:53:34 INFO - 'LOGNAME': 'cltbld', 08:53:34 INFO - 'MAIL': '/var/mail/cltbld', 08:53:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:53:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:53:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:34 INFO - 'MOZ_NO_REMOTE': '1', 08:53:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:53:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:34 INFO - 'NO_EM_RESTART': '1', 08:53:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:34 INFO - 'PWD': '/builds/slave/test', 08:53:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:53:34 INFO - 'RUST_BACKTRACE': '1', 08:53:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:53:34 INFO - 'SHELL': '/bin/bash', 08:53:34 INFO - 'SHLVL': '1', 08:53:34 INFO - 'STYLO_FORCE_ENABLED': '1', 08:53:34 INFO - 'TERM': 'linux', 08:53:34 INFO - 'TMOUT': '86400', 08:53:34 INFO - 'USER': 'cltbld', 08:53:34 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280', 08:53:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:34 INFO - '_': '/tools/buildbot/bin/python'} 08:53: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/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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 08:53:34 INFO - mozversion application_buildid: 20170726150338 08:53:34 INFO - mozversion application_changeset: 388d81ed93fa640f91d155f36254667c734157cf 08:53:34 INFO - mozversion application_display_name: Nightly 08:53:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:53:34 INFO - mozversion application_name: Firefox 08:53:34 INFO - mozversion application_remotingname: firefox 08:53:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:53:34 INFO - mozversion application_vendor: Mozilla 08:53:34 INFO - mozversion application_version: 56.0a1 08:53:34 INFO - mozversion platform_buildid: 20170726150338 08:53:34 INFO - mozversion platform_changeset: 388d81ed93fa640f91d155f36254667c734157cf 08:53:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:53:34 INFO - mozversion platform_version: 56.0a1 08:53:34 INFO - using testdate: 1501084414 08:53:34 INFO - actual date: 1501084414 08:53:34 INFO - starting webserver on 'localhost:54187' 08:53:34 INFO - SUITE-START | Running 2 tests 08:53:34 INFO - TEST-START | dromaeo_css 08:53:34 INFO - Initialising browser for dromaeo_css test... 08:53:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54187/getInfo.html -profile /tmp/tmpvfBsPA/profile 08:53:34 INFO - TEST-INFO | started process 15773 (/builds/slave/test/build/application/firefox/firefox http://localhost:54187/getInfo.html) 08:53:40 INFO - TEST-INFO | 15773: exit 0 08:53:40 INFO - Browser initialized. 08:53:40 INFO - Running cycle 1/1 for dromaeo_css test... 08:53:40 INFO - TEST-INFO | started process 16001 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvfBsPA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 08:53:41 INFO - PID 16001 | 08:53:41 INFO - PID 16001 | (/builds/slave/test/build/application/firefox/firefox:16056): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 08:53:41 INFO - PID 16001 | 08:53:52 INFO - PID 16001 | RSS: Main: 170500096 08:53:52 INFO - PID 16001 | 08:56:50 INFO - PID 16001 | Cycle 1(1): loaded http://localhost:54187/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54187/tests/dromaeo/cssquery-ext.html) 08:56:52 INFO - PID 16001 | RSS: Main: 174608384 08:56:52 INFO - PID 16001 | 08:59:54 INFO - PID 16001 | Cycle 1(1): loaded http://localhost:54187/tests/dromaeo/cssquery-ext.html (next: http://localhost:54187/tests/dromaeo/cssquery-jquery.html) 08:59:55 INFO - PID 16001 | RSS: Main: 171978752 08:59:55 INFO - PID 16001 | 09:02:56 INFO - PID 16001 | Cycle 1(1): loaded http://localhost:54187/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54187/tests/dromaeo/cssquery-mootools.html) 09:02:57 INFO - PID 16001 | RSS: Main: 173817856 09:02:57 INFO - PID 16001 | 09:05:59 INFO - PID 16001 | Cycle 1(1): loaded http://localhost:54187/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54187/tests/dromaeo/cssquery-prototype.html) 09:06:00 INFO - PID 16001 | RSS: Main: 172388352 09:06:00 INFO - PID 16001 | 09:09:04 INFO - PID 16001 | Cycle 1(1): loaded http://localhost:54187/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54187/tests/dromaeo/cssquery-yui.html) 09:09:04 INFO - PID 16001 | RSS: Main: 173690880 09:09:04 INFO - PID 16001 | 09:12:06 INFO - PID 16001 | Cycle 1(1): loaded http://localhost:54187/tests/dromaeo/cssquery-yui.html (next: http://localhost:54187/tests/dromaeo/cssquery-dojo.html) 09:12:06 INFO - PID 16001 | RSS: Main: 176541696 09:12:06 INFO - PID 16001 | 09:12:06 INFO - PID 16001 | __start_tp_report 09:12:06 INFO - PID 16001 | _x_x_mozilla_page_load 09:12:06 INFO - PID 16001 | _x_x_mozilla_page_load_details 09:12:06 INFO - PID 16001 | |i|pagename|runs| 09:12:06 INFO - PID 16001 | |0;dojo.html;3054.419660264551;3088.737457316128;3098.6901309869017;3112.782105252632;3115.9221019474526;6023.337432882379;6224.502039836813;6245.063240513925;6252.906206406908;6263.592866463677;6016.578839481238;6108.602940808855;6124.479419249369;6138.815835524942;6178.814635560941;5544.833654990337;5553.8333849984565;5573.5819813514145;5586;5588.944110558883;5587.7485513151805;5604.887902241952;5621.4940655341015;5630.71846407678;5635.6618602883955;5561.5272701820395;5590.888182236353;5594.160875868612;5647.068233741427;5699;5622.212890195376;5693.857653558653;5698.259226300595;5718.942810571883;5731.799387021434;5611.2144299798065;5612.130119831432;5644.802431914904;5666.206731057655;5689.886202275973;5858.970705146489;6034.6982650867285;6037.969810150965;6110.26676798787;6175.505959523227;5832.6792026438525;6475.643839588821;6531.575447595891;6541.869162616721;6565.638889861056;6573.112629794964;6578.375054369827;6654.26803051664;6673.432758215509;6689.899651505231;6001.279846418458;6105.633661980295;6122.448979591769;6195.783147589857;6220.813375598785;6758.76344327956;6770.932290677129;6789.321067893171;6792.981052842113;6806.047153398528;6430.7106180222;6450.8064758057335;6463.48292136628;6465.806025819233;6473.06140609609;6424.325445828121;6537.607743535403;6537.70580323877;6538.215414150332;6625.3043430440075;6155.907661385083;6544.443722283564;6545.934540654533;6561.311062338481;6644.269130395653;6575.44108750752;6578.440832529289;6632.137822083098;6665.266820649725;6681.33186681328;5180.559652429511;5267.578593712494;5284.656497327662;5356.571474282048;5363.517283444508;4493.370928070073;4594.448666160082;4606.884827879263;4622.791974361162;4629.375034370384;5749.051406517878;5758.88482230353;5762.510186634099;5781.103928891029;5809.302883653989;6592.30780768022;6604.8348791280605;6674.098996635489;6700.329967003358;6706.932930670729;5563.109902415594;5662.915056274159;5696.487316141567;5703.344115426696;5707.4577915098;5765.769369225268;5789.594728368974;5811.2445382100905;5836.708164591753;5845.941540584539;6401.359864013654;6429.614223146533;6453;6460.192475940507;6467.159269294962;6585.670716464062;6600.570962887397;6616.7022483987375;6661;6710.19477662664;6237.5009999200865;6376.521760868046;6403.455706264834;6487.513436492188;6499.805005849832;6434.774782882577;6449.871002579828;6457.934440817213;6490.415862572391;6495.935040649533;6535.215774107138;6577.079208910661;6616.569922954993;6617.966910165611;6621.404073633396;6396.072569477493;6401.583897046677;6402.135711678864;6403.423691867754;6417.8716425672155;6387.297397286202;6406;6407.743690252524;6415.101885736094;6420.325865784025;6317.526185536011;6366.77716279928;6382.96808515973;6462.321456247027;6489.286178520453;6476.902846457401;6500.772472963424;6529.575577587441;6586.70359833799;6589.27517973013;6140.078988151813;6209.782657606962;6261.96869015652;6269.905951410641;6323.9683801580695 09:12:06 INFO - PID 16001 | |1;ext.html;820.8153165537755;857.7855536115972;877.0703054761951;877.3945977275675;1180.9822852657217;32185.67814321856;44591.33113003303;44778;44795.3280700789;44810.10379792402;44580.77709611447;44595;45098.32352514715;45177.77411112948;45289.547104528945;7734.535927844319;7830.960845195795;7907.16974717655;7955.24425179607;7956.48282861615;7843.176466471024;7874.330681892046;7913.960430197841;7941.682332706685;7958.602069896511;7903.802404939865;7956.283934445899;7961.323287520568;7981.48120372174;7989.760307190793;7937.722179723711;7956.48282861615;7956.840863182733;7958.602069896482;7976.920230797676;7725.150233474314;7728;7763.417743669247;7794.181610930827;7811.882821757678;40518.79740601307;40732;40942.181156377;41022.79488602568;41063.794681026404;3600;3628.8548458061646;3643.0710168907026;3646.1978364759975;3673.081729567608;32020.359592808247;32639.184020399443;32641.34717305664;32739.50890736641;32746.6725332746;4972.204447288416;5006.924896126561;5028.522290382444;5053.797848086109;5071.442141364484;190998;191263.04368478208;191350;191504;191838;50046.24930626043;50187.24719129216;50358.244626330634;50395;50489.49510504848;51576.48423515792;51808.740956294976;51861.222081668806;51916.22125668118;52024.21963670548;14621.122732636077;14860.70278594422;14870;16356.83643163577;16882.409115680894;12157.270563766026;12472.625821225378;12650.873491265154;12706.174098683556;12713.80929286043;14834.629134271514;14918.776218356734;14924.552263432115;14950.62623434401;15121.319540620478;6231;6358.8410289742205;6361.459275961595;6456.612603243821;6470.870582588322;989.4360214677708;1006;1014.9746256343503;1037.9245489556076;1045.1847558904067;23721.869158878504;24341.878290608787;24403;24405.755942440705;24512.509749804904;1103.5916710817048;1104.8563686720836;1127.1039523578775;1129.813580759182;1134.6822889590928;12208.328541930101;12487.562935297221;12628.368581570885;12675.746485070431;12770;233396;234573;234911;234988;235982;57278.7136064317;57331.426685733444;57427.42572574221;57650.13524797132;57738.7113064432;1526.778617100492;1536.178144692584;1550.627849316156;1553.627129488937;1557.9143147127018;23301.650475242546;23494.53010939806;23524.52950941006;23593.29220123399;23635.29094127179;40313.79843100766;40405;40469.59530404776;40545.797271013456;40559.797201013804;195263;195857;196687;196957;197038;37287.81356093202;39011;39242.41136382899;39661.405078923264;39867.60132398639;48529.27206091982;50477.242841358144;50593;50644.746776265885;50789.23816142687;35015;35353.82323088368;35494.822525887204;35677.28645427025;36065.098372540895;33778.3244335107;35029.824850875586;35108.29783404266;35314.293714126085;35661.465078023335;38827;39236;39245.41131883081;39411.40882886702;40147.39778903377;33594.664053359156;33638.83180584081;34845.825770870986;35000.474992875636;35304.823475882455;23718.169864054675;24610.261692149263;25271.620925686497;25446.781827139173;25493.61759573645 09:12:06 INFO - PID 16001 | |2;jquery.html;567.6688166250144;588.6497533967288;590.4567797626183;592.4431034827262;605.4490413723513;22620.208292709758;23457;23665.526689466224;23820.5235895282;23987.64018539719;26279.86860065697;26895;26944.191674249803;26961.32596685084;26965.46069078622;6572.934270657292;6704.832379190535;6713.56361836479;6730.326967303279;6733.259341472459;6190.78332258371;6507;6519.771807986722;6524;6749.2913244109395;6445.291017988017;6464.935350646504;6478.805635830909;6486.221653401599;6486.610803351814;6418.775342863;6450.8064758057335;6451.677416129198;6468.450181734558;6469.353064693493;5954.07711804671;5989.431004054608;6019.488343490809;6031.155638210654;6037.124616930564;28030.299242518897;29456;29618.259543511584;29889.402211955858;29978.85010574955;1872.3166044393834;1897.6204759048107;1898.8670793044491;1901.9619607607908;1904.9333273335506;18986.145623446977;19373.32193373225;19565.80434195654;19641.31255406054;19683.409497715093;2377.23780178633;2385.5109702510945;2440.572899742556;2444.413340798196;2448.8408253463467;138613;144227.27886360502;144880;144958;145281;14655.85344146545;15154.090754554541;15176.165310908005;15247.847521524865;15630.374785008473;23379.53240935172;23419.06323747031;23447.765522344558;23545.41136471602;23598.4100397488;9500.23998080152;9517.857232141523;9606.135447809731;9681.128698417177;9683.208606407696;8232.62953167097;8564.357673174536;8612.956935215409;8762.561871906506;8789.208971192624;8005.679772809022;8969.14793094668;8985.550722463851;9209.125133112344;9253.953730231306;2715.9592606110923;2716.348076461635;2721.877515511807;2772.819766715157;2805.8176218545727;935.5930170375908;949.6723630347519;962.6534447598859;974.2303580171726;991.4101109839634;19612.11745471457;19761.308354207533;23381.7661823383;24899.751002490106;25234.495310094062;995.1740055753708;1006.3257617396362;1010.9241806864515;1044.3055368180228;1046.576136664652;10566.735831604117;11031.669049928514;11495.252808567417;13078.636234548549;14300.785488217685;470621;495845;516997;545313;545769;125254;128248;128836;132657;132810;1288.368699337318;1292.4442489729317;1304.6281809684194;1325.555938760526;1337.732453509302;21327.7867221332;21708.240211592518;21723.456913576654;21853.562928741656;21969.560608787415;61102.38897611145;61418.385816143054;62753.372466274755;63136.05295920473;64591.354086460415;74422.62788686239;80244;80672;80765.19234807577;81039.18960810316;16415.507534773977;17407.303707851544;17419.738703919706;20589.58820823605;21180;22050;23506.529869402857;23521.52956940886;23920.64119038181;24157.39606509781;18677.533061673566;18870.433886983414;18975.715364269272;18997.43007709771;19118.426447206606;16705.749413759047;17176.570585735455;17548.561285967444;17677.558061048578;17865.106744662455;18540.721889171404;19249.51876203106;19305.227790888206;19589.118489667715;19611.11749971226;20326.491837704176;20467.4883127923;20582.485437864172;20599.485012874797;20789.688154677995;18663.533411664273;18794.71807922855;18947.526311842314;18955.526111847314;19020.334288299844 09:12:06 INFO - PID 16001 | |3;mootools.html;1363.5636596289187;1408.6055904346786;1415.0519152168047;1419.914805111694;1421.097603022081;13499.865001349983;14057.086289391182;14097.647558811022;14105;14196.858031419708;14029.158250504952;14313.928430357833;14363.138211707304;14396.424143034292;14457.92771036146;2647.2587675450854;2699.932501687454;2701.257154282578;2707;2708.214617760847;2403.387136280246;2481.677381940345;2577.2783620586174;2667.359833639932;2758.8758505867186;2654.3496843273424;2687.1535466328046;2692.394211302461;2694.366823796406;2697.892084316625;2381.130887226159;2395.35325462126;2408.409939564809;2409.807145463007;2411.1560953666253;2503.7120731115792;2525.6211568264725;2548.1845809341016;2552.34915096651;2562.6161872588777;13624.386902589307;13939.09395889264;13956.302184890716;13961.511347102803;13963.720725585432;7557.206493318233;7854.646540905615;7876.684932602689;7877.842443151105;7880.448368614199;5012.097822391968;5062.848114556569;5132.332796736438;5228.424873263938;5234;1806.6747985362629;1807.0874208524742;1819.8999055051968;1862.1713337564788;1869.5139263791377;22448.438789030242;22658;22663.660045099336;22727.886360568093;23204.999999999833;6234.750609975641;6380.138681277968;6519.608823470607;6589.868202636017;6616.569922954993;15590.844091559165;15912.124833134289;15984.120873351845;16014.19929003545;16033.358665653477;7312.415006799443;7455.589942553103;7508.887366689504;7512.887306690404;7540.698372065166;6778.356056174655;6856.897146542706;6963.1296087989;6966.512344135963;6971.093757811452;4886.389201349832;4987.850364489072;4997.575206107449;5050.898982020339;5081.5426611605135;1415.0801978713712;1456.9708605827825;1468.9706205875823;1481.6962522682825;1489.0395694776807;879.2526352600366;883.8364902492931;889.5774507109201;912.7261821453537;929.3262384770987;8726.345524085718;8833.116688331243;8925.285977121815;8998.235150012322;9000.414973026733;969.2294625772528;983.6557204978201;1018.9949050254701;1021.9386836789816;1021.99489002556;4946.802127914843;5032.270320803536;5107.080725469451;5230.450802665741;5394.433584473652;18473.44579662612;18521;18650.81349186491;19153.425397237825;19184.328548501013;18249.962520613608;18881.905590472234;19035.238590456505;19246.4226073218;19501.609967800847;10452.059314661718;10465.84301235482;10606.893931060746;10650.307729997525;10667.573297068186;7363.300486453778;7739.92260077392;7775.844483110419;7931.60341982887;8013.679452822055;15570.221488925281;16053.117078560792;16148.515544533682;16303.755443668591;16337.918310408371;15850.286737096627;16394.098324591785;16484.67030659356;16901.070441125856;17108;10850.782984340427;11507.942460287644;11539.307641541534;11556.47995840206;11566.248193867372;12886.420111094836;13005.609831705064;13046.934765326112;13061.21632702041;13069.934650326688;10193;10361.533730981973;10938.72653183645;10976;11092.279001865176;10399.636012739518;10656.200784941006;10738.14094872424;10825.188110891557;11029.338239705643;9546.093121153479;9565.61737530491;9663.275254356093;9698.175655069115;9726.076022777825;6730.25967143604;7045.189803172496;7046.894296585453;7051.964740176471;7209.134903811576;6739.427148692416;7193.964030179815;7294.489385742947;7340.706371745284;7446.7021319146625 09:12:06 INFO - PID 16001 | |4;prototype.html;484.7511280149299;497.3141051834281;508.17168016133695;517.7023211653297;524.7900839664135;6096.847578810526;6152.753889844412;6176.413240742134;6182.381761823815;6254.249490061194;5741.282339707536;5930.288365396159;5939.287285525732;5987.341392446828;6080.422359875804;4869.829555965542;4891.559759621633;4891.779869905863;4899.559039686445;4930.408350997867;4863.2461968394955;4876.780544875472;4895.069936712013;4923.9753801230945;4955.504449555052;4663.48701642819;4853.301344529405;4856.854294371175;4870.439899411577;4907.337509436234;4836.564709176191;4860.222364421693;4898.510148985109;4905.068037072945;4932.186189278765;4339.327404252346;4343.913121737579;4370.628496577795;4376.190404775095;4377.518472967971;5933.406659334075;5955;5982.68380956189;5984.281886173643;5989.8802023959715;5617.32592088952;5640.605157638967;5654.264945557051;5667.688277144796;5672.205891175197;6314.147590075327;6388;6411.230652321751;6457.708458308357;6470.514711396617;4777.904441911177;4805.303231031481;4836.830710925136;4841.7337046462435;4843;6726.12560367154;6770.898436523506;6834.760783372558;6857;6858.862822743517;4857;4893.339399181136;4899.975500122477;4923.77842997066;4946.332245146931;4938.135826230396;4985.925211121836;4992.525710057539;5023.34696489454;5043.470435604208;5561.304836895388;5591.720413979285;5609.635373700697;5633.971770162738;5647.717614119278;6365.957616913293;6378.266499352541;6378.712957916905;6421.871562568723;6456.386643268882;1658.635100277937;1699.6600679864077;1707.59017835721;1708.7693161423297;1761.3130878957218;1365.4060483689427;1405.6626409661608;1452.847451017649;1454.861788130126;1465.7068586282787;5573.665580065209;5585.69278689676;5595.8528501657065;5604.607677462538;5647.915281270784;6318;6379.936200638027;6432;6552.213734351908;6602.207735071726;5578.888422231614;5601.131824567198;5602.971985140048;5657.698729292286;5736.5410767139365;3461.9495190627026;3467.2372078142766;3470.410030294856;3497.3355062538035;3543.4153364695053;6352.555321127476;6382.744690212339;6407.775727849503;6409.871802563923;6410.4871610272;6738.157730283715;6756.628385438848;6813.2846051163915;6816.171288915144;6820.658967051628;6412.422881940648;6421.5825971311715;6428.871422571522;6432.71052802625;6462.739765745822;6498.545101842826;6587.571807832667;6609.372109649576;6657.3342665733035;6693.598384096969;6542.149520562487;6568;6580.276169621242;6599.525178058228;6645.634490103091;6316.494680425647;6365.185922012204;6376.043593461018;6417.358264173546;6437.519370544895;4853.126437241189;4892.755362231803;4904.313396124617;4969.6272779542405;4983.700977941335;6600.768973085919;6632.933670663231;6667.2666006738245;6684.465242780663;6702.865942681216;6486.740530378732;6497.9025314619375;6504.6015106752275;6518.348165183634;6522.478201743944;6441.1626488556185;6441.3880681335195;6461.90147674887;6481.805545833632;6497;6070.908936366046;6217.533684973736;6274.403931626488;6375.6174629522375;6382.042693595998;6253.96873015632;6347.111404403295;6354.23749150105;6403.583767055126;6434.195725534309;6085.817425477243;6122.969385153046;6131.509479241739;6151.5386346025125;6160.353162917829 09:12:06 INFO - PID 16001 | |5;yui.html;899.2536194958185;912.3294378631707;915.8214148241095;931.5388882503162;938.9389689670173;18081.186346614402;18980.620387592273;19136.13887375068;19147.425577232687;19215.23139074439;20798.792012079874;21457;21598.13607455704;21677.67483487749;21715.3485395438;1031.5409642709017;1046.3930920066343;1049.2759995603049;1057.4184198690689;1059.5920570580306;1015.451656105702;1055.1980494823952;1055.5830446973441;1056.1972900595565;1058.253930978662;1040.1730624153815;1112.8608923884515;1114.5430373546847;1114.8439218509416;1115.7489564847926;1101.031092638473;1102.7077824376547;1103.0293341859192;1103.0568863621647;1103.7957977774097;811.148294290998;811.3022800391658;812.7480481050893;813.5769399912072;814.0313027497259;19312;19454.22183112674;19631.607367852564;19833.603327933506;19856.900715496475;20544.28095016667;20592.27927022547;20603.381898543066;20916.895415522824;20918;13758.793618095737;14061.5078472254;14085.64785880351;14086.366113524917;14188.645283867883;3537.2218112015316;3579.0336609115393;3602.2435288589427;3618.113562177257;3619.855205791765;123542;125266;127066.36466817607;131179.99999999904;132493;14899.70200595982;15349.846501535065;15835.208239588204;15945.681086378207;15979.920100399424;28024;28179.71820281812;28923;29016.274593135342;29035.12894613165;8259.752207433787;8318;8376.162383761575;8380.958095209486;8395.286400656012;4234.0896707207485;4312.719673221231;4326.264535029052;4343.739375637472;4345.391645169679;5225.660332078479;5263.526282634582;5324;5332.226827110047;5373.973130134403;967.0716112531908;994.9054839790208;1007.385494848142;1008.9293749437469;1010.9646162384429;660.8281846719791;668.6589839181955;684.8561802021532;705.8235441139715;713.4043074033136;10949.233553651167;11108.16688748347;11182.273152245078;11270.323780573028;11464.31214127155;650.3983814971227;670.3899451499112;682.7200847652439;700.0758998122429;706.9469789765685;4824.710517368969;4898.951010489849;5054.873628159326;5058.671186372874;5127.487251274917;100058.499707501;103278.48360758148;105540.47229763801;105767;106689;87431;90312;93950.53024734833;94645;98802.5059874696;77580.2241977573;82151.58924205341;84086;84213.57893210617;86288;9718.368306060083;9719.416834989923;9779.26655500826;9952.800943980936;9956.452395118338;36023.27953440864;37790.811045944596;38221.80889095537;38428;39031.804840975616;44602.3309650362;46232.30651540297;47619;48419.51580484291;48538.27192592043;14935.253237337873;15239.238038098272;15449.459268925533;15550.922245389153;15747.606309842346;16833.15834208309;20929.89535052315;21288.467788304795;21614.243501477373;21663.916804159413;18196.363127290482;18353.54116147107;18866.434006979813;18922.905385472983;19682.409527714193;15170.089794612359;15358.38566457374;17249.137543123044;19361.32235371755;19831.40505784829;17022;17613.82386176122;17697.82302176962;17758.37845675395;17889.28442862271;12916.806247906477;14279.71440571162;14464.710705786036;14651.560453186421;14998.2500874958;14337.56987290383;14349.497767578503;14506.274686265433;14696.191709456083;14838.703225935637 09:12:06 INFO - PID 16001 | __end_tp_report 09:12:06 INFO - PID 16001 | __start_cc_report 09:12:06 INFO - PID 16001 | _x_x_mozilla_cycle_collect,349 09:12:06 INFO - PID 16001 | __end_cc_report 09:12:06 INFO - PID 16001 | __startTimestamp1501085526889__endTimestamp 09:12:06 INFO - PID 16001 | 09:12:06 INFO - PID 16001 | ------- Summary: start ------- 09:12:06 INFO - PID 16001 | Number of tests: 6 09:12:06 INFO - PID 16001 | 09:12:06 INFO - PID 16001 | [#0] dojo.html Cycles:165 Average:6059.16 Median:6317.53 stddev:717.15 (11.4%) stddev-sans-first:679.51 09:12:06 INFO - PID 16001 | Values: 3054.4 3088.7 3098.7 3112.8 3115.9 6023.3 6224.5 6245.1 6252.9 6263.6 6016.6 6108.6 6124.5 6138.8 6178.8 5544.8 5553.8 5573.6 5586.0 5588.9 5587.7 5604.9 5621.5 5630.7 5635.7 5561.5 5590.9 5594.2 5647.1 5699.0 5622.2 5693.9 5698.3 5718.9 5731.8 5611.2 5612.1 5644.8 5666.2 5689.9 5859.0 6034.7 6038.0 6110.3 6175.5 5832.7 6475.6 6531.6 6541.9 6565.6 6573.1 6578.4 6654.3 6673.4 6689.9 6001.3 6105.6 6122.4 6195.8 6220.8 6758.8 6770.9 6789.3 6793.0 6806.0 6430.7 6450.8 6463.5 6465.8 6473.1 6424.3 6537.6 6537.7 6538.2 6625.3 6155.9 6544.4 6545.9 6561.3 6644.3 6575.4 6578.4 6632.1 6665.3 6681.3 5180.6 5267.6 5284.7 5356.6 5363.5 4493.4 4594.4 4606.9 4622.8 4629.4 5749.1 5758.9 5762.5 5781.1 5809.3 6592.3 6604.8 6674.1 6700.3 6706.9 5563.1 5662.9 5696.5 5703.3 5707.5 5765.8 5789.6 5811.2 5836.7 5845.9 6401.4 6429.6 6453.0 6460.2 6467.2 6585.7 6600.6 6616.7 6661.0 6710.2 6237.5 6376.5 6403.5 6487.5 6499.8 6434.8 6449.9 6457.9 6490.4 6495.9 6535.2 6577.1 6616.6 6618.0 6621.4 6396.1 6401.6 6402.1 6403.4 6417.9 6387.3 6406.0 6407.7 6415.1 6420.3 6317.5 6366.8 6383.0 6462.3 6489.3 6476.9 6500.8 6529.6 6586.7 6589.3 6140.1 6209.8 6262.0 6269.9 6324.0 09:12:06 INFO - PID 16001 | 09:12:06 INFO - PID 16001 | [#1] ext.html Cycles:180 Average:39003.62 Median:20480.45 stddev:54018.87 (263.8%) stddev-sans-first:54094.32 09:12:06 INFO - PID 16001 | Values: 820.8 857.8 877.1 877.4 1181.0 32185.7 44591.3 44778.0 44795.3 44810.1 44580.8 44595.0 45098.3 45177.8 45289.5 7734.5 7831.0 7907.2 7955.2 7956.5 7843.2 7874.3 7914.0 7941.7 7958.6 7903.8 7956.3 7961.3 7981.5 7989.8 7937.7 7956.5 7956.8 7958.6 7976.9 7725.2 7728.0 7763.4 7794.2 7811.9 40518.8 40732.0 40942.2 41022.8 41063.8 3600.0 3628.9 3643.1 3646.2 3673.1 32020.4 32639.2 32641.3 32739.5 32746.7 4972.2 5006.9 5028.5 5053.8 5071.4 190998.0 191263.0 191350.0 191504.0 191838.0 50046.2 50187.2 50358.2 50395.0 50489.5 51576.5 51808.7 51861.2 51916.2 52024.2 14621.1 14860.7 14870.0 16356.8 16882.4 12157.3 12472.6 12650.9 12706.2 12713.8 14834.6 14918.8 14924.6 14950.6 15121.3 6231.0 6358.8 6361.5 6456.6 6470.9 989.4 1006.0 1015.0 1037.9 1045.2 23721.9 24341.9 24403.0 24405.8 24512.5 1103.6 1104.9 1127.1 1129.8 1134.7 12208.3 12487.6 12628.4 12675.7 12770.0 233396.0 234573.0 234911.0 234988.0 235982.0 57278.7 57331.4 57427.4 57650.1 57738.7 1526.8 1536.2 1550.6 1553.6 1557.9 23301.7 23494.5 23524.5 23593.3 23635.3 40313.8 40405.0 40469.6 40545.8 40559.8 195263.0 195857.0 196687.0 196957.0 197038.0 37287.8 39011.0 39242.4 39661.4 39867.6 48529.3 50477.2 50593.0 50644.7 50789.2 35015.0 35353.8 35494.8 35677.3 36065.1 33778.3 35029.8 35108.3 35314.3 35661.5 38827.0 39236.0 39245.4 39411.4 40147.4 33594.7 33638.8 34845.8 35000.5 35304.8 23718.2 24610.3 25271.6 25446.8 25493.6 09:12:06 INFO - PID 16001 | 09:12:06 INFO - PID 16001 | [#2] jquery.html Cycles:180 Average:36985.47 Median:13153.08 stddev:87164.63 (662.7%) stddev-sans-first:87366.26 09:12:06 INFO - PID 16001 | Values: 567.7 588.6 590.5 592.4 605.4 22620.2 23457.0 23665.5 23820.5 23987.6 26279.9 26895.0 26944.2 26961.3 26965.5 6572.9 6704.8 6713.6 6730.3 6733.3 6190.8 6507.0 6519.8 6524.0 6749.3 6445.3 6464.9 6478.8 6486.2 6486.6 6418.8 6450.8 6451.7 6468.5 6469.4 5954.1 5989.4 6019.5 6031.2 6037.1 28030.3 29456.0 29618.3 29889.4 29978.9 1872.3 1897.6 1898.9 1902.0 1904.9 18986.1 19373.3 19565.8 19641.3 19683.4 2377.2 2385.5 2440.6 2444.4 2448.8 138613.0 144227.3 144880.0 144958.0 145281.0 14655.9 15154.1 15176.2 15247.8 15630.4 23379.5 23419.1 23447.8 23545.4 23598.4 9500.2 9517.9 9606.1 9681.1 9683.2 8232.6 8564.4 8613.0 8762.6 8789.2 8005.7 8969.1 8985.6 9209.1 9254.0 2716.0 2716.3 2721.9 2772.8 2805.8 935.6 949.7 962.7 974.2 991.4 19612.1 19761.3 23381.8 24899.8 25234.5 995.2 1006.3 1010.9 1044.3 1046.6 10566.7 11031.7 11495.3 13078.6 14300.8 470621.0 495845.0 516997.0 545313.0 545769.0 125254.0 128248.0 128836.0 132657.0 132810.0 1288.4 1292.4 1304.6 1325.6 1337.7 21327.8 21708.2 21723.5 21853.6 21969.6 61102.4 61418.4 62753.4 63136.1 64591.4 74422.6 80244.0 80672.0 80765.2 81039.2 16415.5 17407.3 17419.7 20589.6 21180.0 22050.0 23506.5 23521.5 23920.6 24157.4 18677.5 18870.4 18975.7 18997.4 19118.4 16705.7 17176.6 17548.6 17677.6 17865.1 18540.7 19249.5 19305.2 19589.1 19611.1 20326.5 20467.5 20582.5 20599.5 20789.7 18663.5 18794.7 18947.5 18955.5 19020.3 09:12:06 INFO - PID 16001 | 09:12:06 INFO - PID 16001 | [#3] mootools.html Cycles:180 Average:8656.28 Median:2405.90 stddev:5824.98 (242.1%) stddev-sans-first:5815.54 09:12:06 INFO - PID 16001 | Values: 1363.6 1408.6 1415.1 1419.9 1421.1 13499.9 14057.1 14097.6 14105.0 14196.9 14029.2 14313.9 14363.1 14396.4 14457.9 2647.3 2699.9 2701.3 2707.0 2708.2 2403.4 2481.7 2577.3 2667.4 2758.9 2654.3 2687.2 2692.4 2694.4 2697.9 2381.1 2395.4 2408.4 2409.8 2411.2 2503.7 2525.6 2548.2 2552.3 2562.6 13624.4 13939.1 13956.3 13961.5 13963.7 7557.2 7854.6 7876.7 7877.8 7880.4 5012.1 5062.8 5132.3 5228.4 5234.0 1806.7 1807.1 1819.9 1862.2 1869.5 22448.4 22658.0 22663.7 22727.9 23205.0 6234.8 6380.1 6519.6 6589.9 6616.6 15590.8 15912.1 15984.1 16014.2 16033.4 7312.4 7455.6 7508.9 7512.9 7540.7 6778.4 6856.9 6963.1 6966.5 6971.1 4886.4 4987.9 4997.6 5050.9 5081.5 1415.1 1457.0 1469.0 1481.7 1489.0 879.3 883.8 889.6 912.7 929.3 8726.3 8833.1 8925.3 8998.2 9000.4 969.2 983.7 1019.0 1021.9 1022.0 4946.8 5032.3 5107.1 5230.5 5394.4 18473.4 18521.0 18650.8 19153.4 19184.3 18250.0 18881.9 19035.2 19246.4 19501.6 10452.1 10465.8 10606.9 10650.3 10667.6 7363.3 7739.9 7775.8 7931.6 8013.7 15570.2 16053.1 16148.5 16303.8 16337.9 15850.3 16394.1 16484.7 16901.1 17108.0 10850.8 11507.9 11539.3 11556.5 11566.2 12886.4 13005.6 13046.9 13061.2 13069.9 10193.0 10361.5 10938.7 10976.0 11092.3 10399.6 10656.2 10738.1 10825.2 11029.3 9546.1 9565.6 9663.3 9698.2 9726.1 6730.3 7045.2 7046.9 7052.0 7209.1 6739.4 7194.0 7294.5 7340.7 7446.7 09:12:06 INFO - PID 16001 | 09:12:06 INFO - PID 16001 | [#4] prototype.html Cycles:180 Average:5413.15 Median:5983.48 stddev:1501.53 (25.1%) stddev-sans-first:1459.46 09:12:06 INFO - PID 16001 | Values: 484.8 497.3 508.2 517.7 524.8 6096.8 6152.8 6176.4 6182.4 6254.2 5741.3 5930.3 5939.3 5987.3 6080.4 4869.8 4891.6 4891.8 4899.6 4930.4 4863.2 4876.8 4895.1 4924.0 4955.5 4663.5 4853.3 4856.9 4870.4 4907.3 4836.6 4860.2 4898.5 4905.1 4932.2 4339.3 4343.9 4370.6 4376.2 4377.5 5933.4 5955.0 5982.7 5984.3 5989.9 5617.3 5640.6 5654.3 5667.7 5672.2 6314.1 6388.0 6411.2 6457.7 6470.5 4777.9 4805.3 4836.8 4841.7 4843.0 6726.1 6770.9 6834.8 6857.0 6858.9 4857.0 4893.3 4900.0 4923.8 4946.3 4938.1 4985.9 4992.5 5023.3 5043.5 5561.3 5591.7 5609.6 5634.0 5647.7 6366.0 6378.3 6378.7 6421.9 6456.4 1658.6 1699.7 1707.6 1708.8 1761.3 1365.4 1405.7 1452.8 1454.9 1465.7 5573.7 5585.7 5595.9 5604.6 5647.9 6318.0 6379.9 6432.0 6552.2 6602.2 5578.9 5601.1 5603.0 5657.7 5736.5 3461.9 3467.2 3470.4 3497.3 3543.4 6352.6 6382.7 6407.8 6409.9 6410.5 6738.2 6756.6 6813.3 6816.2 6820.7 6412.4 6421.6 6428.9 6432.7 6462.7 6498.5 6587.6 6609.4 6657.3 6693.6 6542.1 6568.0 6580.3 6599.5 6645.6 6316.5 6365.2 6376.0 6417.4 6437.5 4853.1 4892.8 4904.3 4969.6 4983.7 6600.8 6632.9 6667.3 6684.5 6702.9 6486.7 6497.9 6504.6 6518.3 6522.5 6441.2 6441.4 6461.9 6481.8 6497.0 6070.9 6217.5 6274.4 6375.6 6382.0 6254.0 6347.1 6354.2 6403.6 6434.2 6085.8 6123.0 6131.5 6151.5 6160.4 09:12:06 INFO - PID 16001 | 09:12:06 INFO - PID 16001 | [#5] yui.html Cycles:180 Average:22400.99 Median:20597.83 stddev:30684.54 (149.0%) stddev-sans-first:30728.14 09:12:06 INFO - PID 16001 | Values: 899.3 912.3 915.8 931.5 938.9 18081.2 18980.6 19136.1 19147.4 19215.2 20798.8 21457.0 21598.1 21677.7 21715.3 1031.5 1046.4 1049.3 1057.4 1059.6 1015.5 1055.2 1055.6 1056.2 1058.3 1040.2 1112.9 1114.5 1114.8 1115.7 1101.0 1102.7 1103.0 1103.1 1103.8 811.1 811.3 812.7 813.6 814.0 19312.0 19454.2 19631.6 19833.6 19856.9 20544.3 20592.3 20603.4 20916.9 20918.0 13758.8 14061.5 14085.6 14086.4 14188.6 3537.2 3579.0 3602.2 3618.1 3619.9 123542.0 125266.0 127066.4 131180.0 132493.0 14899.7 15349.8 15835.2 15945.7 15979.9 28024.0 28179.7 28923.0 29016.3 29035.1 8259.8 8318.0 8376.2 8381.0 8395.3 4234.1 4312.7 4326.3 4343.7 4345.4 5225.7 5263.5 5324.0 5332.2 5374.0 967.1 994.9 1007.4 1008.9 1011.0 660.8 668.7 684.9 705.8 713.4 10949.2 11108.2 11182.3 11270.3 11464.3 650.4 670.4 682.7 700.1 706.9 4824.7 4899.0 5054.9 5058.7 5127.5 100058.5 103278.5 105540.5 105767.0 106689.0 87431.0 90312.0 93950.5 94645.0 98802.5 77580.2 82151.6 84086.0 84213.6 86288.0 9718.4 9719.4 9779.3 9952.8 9956.5 36023.3 37790.8 38221.8 38428.0 39031.8 44602.3 46232.3 47619.0 48419.5 48538.3 14935.3 15239.2 15449.5 15550.9 15747.6 16833.2 20929.9 21288.5 21614.2 21663.9 18196.4 18353.5 18866.4 18922.9 19682.4 15170.1 15358.4 17249.1 19361.3 19831.4 17022.0 17613.8 17697.8 17758.4 17889.3 12916.8 14279.7 14464.7 14651.6 14998.3 14337.6 14349.5 14506.3 14696.2 14838.7 09:12:06 INFO - PID 16001 | -------- Summary: end -------- 09:12:06 INFO - PID 16001 | 09:12:07 INFO - TEST-INFO | 16001: exit 0 09:12:07 INFO - TEST-OK | dromaeo_css | took 1113274ms 09:12:07 INFO - TEST-START | kraken 09:12:07 INFO - Initialising browser for kraken test... 09:12:07 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54187/getInfo.html -profile /tmp/tmpRC0IGz/profile 09:12:07 INFO - TEST-INFO | started process 16371 (/builds/slave/test/build/application/firefox/firefox http://localhost:54187/getInfo.html) 09:12:13 INFO - TEST-INFO | 16371: exit 0 09:12:13 INFO - Browser initialized. 09:12:13 INFO - Running cycle 1/1 for kraken test... 09:12:13 INFO - TEST-INFO | started process 16601 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRC0IGz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:12:14 INFO - PID 16601 | 09:12:14 INFO - PID 16601 | (/builds/slave/test/build/application/firefox/firefox:16655): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:12:14 INFO - PID 16601 | 09:12:25 INFO - PID 16601 | RSS: Main: 167301120 09:12:25 INFO - PID 16601 | 09:12:27 INFO - PID 16601 | Cycle 1(1): loaded http://localhost:54187/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54187/tests/kraken/driver.html?testName=audio-beat-detection) 09:12:28 INFO - PID 16601 | RSS: Main: 173543424 09:12:28 INFO - PID 16601 | 09:12:31 INFO - PID 16601 | Cycle 1(1): loaded http://localhost:54187/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54187/tests/kraken/driver.html?testName=audio-dft) 09:12:32 INFO - PID 16601 | RSS: Main: 173985792 09:12:32 INFO - PID 16601 | 09:12:35 INFO - PID 16601 | Cycle 1(1): loaded http://localhost:54187/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54187/tests/kraken/driver.html?testName=audio-fft) 09:12:35 INFO - PID 16601 | RSS: Main: 168726528 09:12:35 INFO - PID 16601 | 09:12:37 INFO - PID 16601 | Cycle 1(1): loaded http://localhost:54187/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54187/tests/kraken/driver.html?testName=audio-oscillator) 09:12:38 INFO - PID 16601 | RSS: Main: 169291776 09:12:38 INFO - PID 16601 | 09:12:40 INFO - PID 16601 | Cycle 1(1): loaded http://localhost:54187/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54187/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:12:41 INFO - PID 16601 | RSS: Main: 169783296 09:12:41 INFO - PID 16601 | 09:12:44 INFO - PID 16601 | Cycle 1(1): loaded http://localhost:54187/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54187/tests/kraken/driver.html?testName=imaging-darkroom) 09:12:44 INFO - PID 16601 | RSS: Main: 172503040 09:12:44 INFO - PID 16601 | 09:12:48 INFO - PID 16601 | Cycle 1(1): loaded http://localhost:54187/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54187/tests/kraken/driver.html?testName=imaging-desaturate) 09:12:49 INFO - PID 16601 | RSS: Main: 172277760 09:12:49 INFO - PID 16601 | 09:12:51 INFO - PID 16601 | Cycle 1(1): loaded http://localhost:54187/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54187/tests/kraken/driver.html?testName=json-parse-financial) 09:12:52 INFO - PID 16601 | RSS: Main: 172916736 09:12:52 INFO - PID 16601 | 09:12:54 INFO - PID 16601 | Cycle 1(1): loaded http://localhost:54187/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54187/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:12:54 INFO - PID 16601 | RSS: Main: 172974080 09:12:54 INFO - PID 16601 | 09:12:56 INFO - PID 16601 | Cycle 1(1): loaded http://localhost:54187/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54187/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:12:57 INFO - PID 16601 | RSS: Main: 173260800 09:12:57 INFO - PID 16601 | 09:12:59 INFO - PID 16601 | Cycle 1(1): loaded http://localhost:54187/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54187/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:12:59 INFO - PID 16601 | RSS: Main: 173453312 09:12:59 INFO - PID 16601 | 09:13:02 INFO - PID 16601 | Cycle 1(1): loaded http://localhost:54187/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54187/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:13:03 INFO - PID 16601 | RSS: Main: 174125056 09:13:03 INFO - PID 16601 | 09:13:06 INFO - PID 16601 | Cycle 1(1): loaded http://localhost:54187/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54187/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:13:07 INFO - PID 16601 | RSS: Main: 174206976 09:13:07 INFO - PID 16601 | 09:13:08 INFO - PID 16601 | Cycle 1(1): loaded http://localhost:54187/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54187/tests/kraken/driver.html?testName=ai-astar) 09:13:09 INFO - PID 16601 | RSS: Main: 175550464 09:13:09 INFO - PID 16601 | 09:13:09 INFO - PID 16601 | __start_tp_report 09:13:09 INFO - PID 16601 | _x_x_mozilla_page_load 09:13:09 INFO - PID 16601 | _x_x_mozilla_page_load_details 09:13:09 INFO - PID 16601 | |i|pagename|runs| 09:13:09 INFO - PID 16601 | |0;ai-astar;99;109;105;110;108;110;111;99;110;111 09:13:09 INFO - PID 16601 | |1;audio-beat-detection;145;127;147;175;127;150;146;119;166;119 09:13:09 INFO - PID 16601 | |2;audio-dft;164;165;163;162;165;162;168;165;166;166 09:13:09 INFO - PID 16601 | |3;audio-fft;78;74;76;182;88;74;73;76;160;77 09:13:09 INFO - PID 16601 | |4;audio-oscillator;79;83;131;79;78;78;141;78;79;123 09:13:09 INFO - PID 16601 | |5;imaging-gaussian-blur;115;114;115;115;115;115;114;115;114;114 09:13:09 INFO - PID 16601 | |6;imaging-darkroom;153;151;153;153;150;150;151;152;150;150 09:13:09 INFO - PID 16601 | |7;imaging-desaturate;95;94;94;95;94;95;95;95;96;94 09:13:09 INFO - PID 16601 | |8;json-parse-financial;54;53;53;55;55;54;53;57;53;53 09:13:09 INFO - PID 16601 | |9;json-stringify-tinderbox;55;54;55;55;55;54;55;56;54;54 09:13:09 INFO - PID 16601 | |10;stanford-crypto-aes;80;78;78;79;79;77;88;87;78;85 09:13:09 INFO - PID 16601 | |11;stanford-crypto-ccm;143;129;132;136;133;139;135;130;128;165 09:13:09 INFO - PID 16601 | |12;stanford-crypto-pbkdf2;185;179;174;174;179;176;190;188;177;184 09:13:09 INFO - PID 16601 | |13;stanford-crypto-sha256-iterative;70;69;69;69;77;67;71;67;71;67 09:13:09 INFO - PID 16601 | __end_tp_report 09:13:09 INFO - PID 16601 | __start_cc_report 09:13:09 INFO - PID 16601 | _x_x_mozilla_cycle_collect,948 09:13:09 INFO - PID 16601 | __end_cc_report 09:13:09 INFO - PID 16601 | __startTimestamp1501085589245__endTimestamp 09:13:09 INFO - PID 16601 | 09:13:09 INFO - PID 16601 | ------- Summary: start ------- 09:13:09 INFO - PID 16601 | Number of tests: 14 09:13:09 INFO - PID 16601 | 09:13:09 INFO - PID 16601 | [#0] ai-astar Cycles:10 Average:107.20 Median:110.50 stddev:4.66 (4.2%) stddev-sans-first:3.89 09:13:09 INFO - PID 16601 | Values: 99.0 109.0 105.0 110.0 108.0 110.0 111.0 99.0 110.0 111.0 09:13:09 INFO - PID 16601 | 09:13:09 INFO - PID 16601 | [#1] audio-beat-detection Cycles:10 Average:142.10 Median:146.50 stddev:19.11 (13.0%) stddev-sans-first:20.24 09:13:09 INFO - PID 16601 | Values: 145.0 127.0 147.0 175.0 127.0 150.0 146.0 119.0 166.0 119.0 09:13:09 INFO - PID 16601 | 09:13:09 INFO - PID 16601 | [#2] audio-dft Cycles:10 Average:164.60 Median:165.00 stddev:1.90 (1.1%) stddev-sans-first:2.00 09:13:09 INFO - PID 16601 | Values: 164.0 165.0 163.0 162.0 165.0 162.0 168.0 165.0 166.0 166.0 09:13:09 INFO - PID 16601 | 09:13:09 INFO - PID 16601 | [#3] audio-fft Cycles:10 Average:95.80 Median:76.00 stddev:40.19 (52.9%) stddev-sans-first:42.11 09:13:09 INFO - PID 16601 | Values: 78.0 74.0 76.0 182.0 88.0 74.0 73.0 76.0 160.0 77.0 09:13:09 INFO - PID 16601 | 09:13:09 INFO - PID 16601 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:78.50 stddev:25.77 (32.8%) stddev-sans-first:26.68 09:13:09 INFO - PID 16601 | Values: 79.0 83.0 131.0 79.0 78.0 78.0 141.0 78.0 79.0 123.0 09:13:09 INFO - PID 16601 | 09:13:09 INFO - PID 16601 | [#5] imaging-gaussian-blur Cycles:10 Average:114.60 Median:115.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 09:13:09 INFO - PID 16601 | Values: 115.0 114.0 115.0 115.0 115.0 115.0 114.0 115.0 114.0 114.0 09:13:09 INFO - PID 16601 | 09:13:09 INFO - PID 16601 | [#6] imaging-darkroom Cycles:10 Average:151.30 Median:151.50 stddev:1.34 (0.9%) stddev-sans-first:1.27 09:13:09 INFO - PID 16601 | Values: 153.0 151.0 153.0 153.0 150.0 150.0 151.0 152.0 150.0 150.0 09:13:09 INFO - PID 16601 | 09:13:09 INFO - PID 16601 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 09:13:09 INFO - PID 16601 | Values: 95.0 94.0 94.0 95.0 94.0 95.0 95.0 95.0 96.0 94.0 09:13:09 INFO - PID 16601 | 09:13:09 INFO - PID 16601 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:1.33 (2.5%) stddev-sans-first:1.41 09:13:09 INFO - PID 16601 | Values: 54.0 53.0 53.0 55.0 55.0 54.0 53.0 57.0 53.0 53.0 09:13:09 INFO - PID 16601 | 09:13:09 INFO - PID 16601 | [#9] json-stringify-tinderbox Cycles:10 Average:54.70 Median:55.00 stddev:0.67 (1.2%) stddev-sans-first:0.71 09:13:09 INFO - PID 16601 | Values: 55.0 54.0 55.0 55.0 55.0 54.0 55.0 56.0 54.0 54.0 09:13:09 INFO - PID 16601 | 09:13:09 INFO - PID 16601 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:79.50 stddev:4.12 (5.2%) stddev-sans-first:4.36 09:13:09 INFO - PID 16601 | Values: 80.0 78.0 78.0 79.0 79.0 77.0 88.0 87.0 78.0 85.0 09:13:09 INFO - PID 16601 | 09:13:09 INFO - PID 16601 | [#11] stanford-crypto-ccm Cycles:10 Average:137.00 Median:135.50 stddev:10.87 (8.0%) stddev-sans-first:11.31 09:13:09 INFO - PID 16601 | Values: 143.0 129.0 132.0 136.0 133.0 139.0 135.0 130.0 128.0 165.0 09:13:09 INFO - PID 16601 | 09:13:09 INFO - PID 16601 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.60 Median:181.50 stddev:5.78 (3.2%) stddev-sans-first:5.90 09:13:09 INFO - PID 16601 | Values: 185.0 179.0 174.0 174.0 179.0 176.0 190.0 188.0 177.0 184.0 09:13:09 INFO - PID 16601 | 09:13:09 INFO - PID 16601 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:69.50 stddev:2.98 (4.3%) stddev-sans-first:3.16 09:13:09 INFO - PID 16601 | Values: 70.0 69.0 69.0 69.0 77.0 67.0 71.0 67.0 71.0 67.0 09:13:09 INFO - PID 16601 | -------- Summary: end -------- 09:13:09 INFO - PID 16601 | 09:13:09 INFO - PID 16601 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 09:13:09 INFO - TEST-INFO | 16601: exit 0 09:13:09 INFO - TEST-OK | kraken | took 62334ms 09:13:09 INFO - SUITE-END | took 1175s 09:13:10 INFO - Completed test suite (00:19:36) 09:13:10 INFO - javascript benchmark 09:13:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8498.163278506252, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3054.419660264551, 3088.737457316128, 3098.6901309869017, 3112.782105252632, 3115.9221019474526, 6023.337432882379, 6224.502039836813, 6245.063240513925, 6252.906206406908, 6263.592866463677, 6016.578839481238, 6108.602940808855, 6124.479419249369, 6138.815835524942, 6178.814635560941, 5544.833654990337, 5553.8333849984565, 5573.5819813514145, 5586.0, 5588.944110558883, 5587.7485513151805, 5604.887902241952, 5621.4940655341015, 5630.71846407678, 5635.6618602883955, 5561.5272701820395, 5590.888182236353, 5594.160875868612, 5647.068233741427, 5699.0, 5622.212890195376, 5693.857653558653, 5698.259226300595, 5718.942810571883, 5731.799387021434, 5611.2144299798065, 5612.130119831432, 5644.802431914904, 5666.206731057655, 5689.886202275973, 5858.970705146489, 6034.6982650867285, 6037.969810150965, 6110.26676798787, 6175.505959523227, 5832.6792026438525, 6475.643839588821, 6531.575447595891, 6541.869162616721, 6565.638889861056, 6573.112629794964, 6578.375054369827, 6654.26803051664, 6673.432758215509, 6689.899651505231, 6001.279846418458, 6105.633661980295, 6122.448979591769, 6195.783147589857, 6220.813375598785, 6758.76344327956, 6770.932290677129, 6789.321067893171, 6792.981052842113, 6806.047153398528, 6430.7106180222, 6450.8064758057335, 6463.48292136628, 6465.806025819233, 6473.06140609609, 6424.325445828121, 6537.607743535403, 6537.70580323877, 6538.215414150332, 6625.3043430440075, 6155.907661385083, 6544.443722283564, 6545.934540654533, 6561.311062338481, 6644.269130395653, 6575.44108750752, 6578.440832529289, 6632.137822083098, 6665.266820649725, 6681.33186681328, 5180.559652429511, 5267.578593712494, 5284.656497327662, 5356.571474282048, 5363.517283444508, 4493.370928070073, 4594.448666160082, 4606.884827879263, 4622.791974361162, 4629.375034370384, 5749.051406517878, 5758.88482230353, 5762.510186634099, 5781.103928891029, 5809.302883653989, 6592.30780768022, 6604.8348791280605, 6674.098996635489, 6700.329967003358, 6706.932930670729, 5563.109902415594, 5662.915056274159, 5696.487316141567, 5703.344115426696, 5707.4577915098, 5765.769369225268, 5789.594728368974, 5811.2445382100905, 5836.708164591753, 5845.941540584539, 6401.359864013654, 6429.614223146533, 6453.0, 6460.192475940507, 6467.159269294962, 6585.670716464062, 6600.570962887397, 6616.7022483987375, 6661.0, 6710.19477662664, 6237.5009999200865, 6376.521760868046, 6403.455706264834, 6487.513436492188, 6499.805005849832, 6434.774782882577, 6449.871002579828, 6457.934440817213, 6490.415862572391, 6495.935040649533, 6535.215774107138, 6577.079208910661, 6616.569922954993, 6617.966910165611, 6621.404073633396, 6396.072569477493, 6401.583897046677, 6402.135711678864, 6403.423691867754, 6417.8716425672155, 6387.297397286202, 6406.0, 6407.743690252524, 6415.101885736094, 6420.325865784025, 6317.526185536011, 6366.77716279928, 6382.96808515973, 6462.321456247027, 6489.286178520453, 6476.902846457401, 6500.772472963424, 6529.575577587441, 6586.70359833799, 6589.27517973013, 6140.078988151813, 6209.782657606962, 6261.96869015652, 6269.905951410641, 6323.9683801580695], "value": 6005.287770870742, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [820.8153165537755, 857.7855536115972, 877.0703054761951, 877.3945977275675, 1180.9822852657217, 32185.67814321856, 44591.33113003303, 44778.0, 44795.3280700789, 44810.10379792402, 44580.77709611447, 44595.0, 45098.32352514715, 45177.77411112948, 45289.547104528945, 7734.535927844319, 7830.960845195795, 7907.16974717655, 7955.24425179607, 7956.48282861615, 7843.176466471024, 7874.330681892046, 7913.960430197841, 7941.682332706685, 7958.602069896511, 7903.802404939865, 7956.283934445899, 7961.323287520568, 7981.48120372174, 7989.760307190793, 7937.722179723711, 7956.48282861615, 7956.840863182733, 7958.602069896482, 7976.920230797676, 7725.150233474314, 7728.0, 7763.417743669247, 7794.181610930827, 7811.882821757678, 40518.79740601307, 40732.0, 40942.181156377, 41022.79488602568, 41063.794681026404, 3600.0, 3628.8548458061646, 3643.0710168907026, 3646.1978364759975, 3673.081729567608, 32020.359592808247, 32639.184020399443, 32641.34717305664, 32739.50890736641, 32746.6725332746, 4972.204447288416, 5006.924896126561, 5028.522290382444, 5053.797848086109, 5071.442141364484, 190998.0, 191263.04368478208, 191350.0, 191504.0, 191838.0, 50046.24930626043, 50187.24719129216, 50358.244626330634, 50395.0, 50489.49510504848, 51576.48423515792, 51808.740956294976, 51861.222081668806, 51916.22125668118, 52024.21963670548, 14621.122732636077, 14860.70278594422, 14870.0, 16356.83643163577, 16882.409115680894, 12157.270563766026, 12472.625821225378, 12650.873491265154, 12706.174098683556, 12713.80929286043, 14834.629134271514, 14918.776218356734, 14924.552263432115, 14950.62623434401, 15121.319540620478, 6231.0, 6358.8410289742205, 6361.459275961595, 6456.612603243821, 6470.870582588322, 989.4360214677708, 1006.0, 1014.9746256343503, 1037.9245489556076, 1045.1847558904067, 23721.869158878504, 24341.878290608787, 24403.0, 24405.755942440705, 24512.509749804904, 1103.5916710817048, 1104.8563686720836, 1127.1039523578775, 1129.813580759182, 1134.6822889590928, 12208.328541930101, 12487.562935297221, 12628.368581570885, 12675.746485070431, 12770.0, 233396.0, 234573.0, 234911.0, 234988.0, 235982.0, 57278.7136064317, 57331.426685733444, 57427.42572574221, 57650.13524797132, 57738.7113064432, 1526.778617100492, 1536.178144692584, 1550.627849316156, 1553.627129488937, 1557.9143147127018, 23301.650475242546, 23494.53010939806, 23524.52950941006, 23593.29220123399, 23635.29094127179, 40313.79843100766, 40405.0, 40469.59530404776, 40545.797271013456, 40559.797201013804, 195263.0, 195857.0, 196687.0, 196957.0, 197038.0, 37287.81356093202, 39011.0, 39242.41136382899, 39661.405078923264, 39867.60132398639, 48529.27206091982, 50477.242841358144, 50593.0, 50644.746776265885, 50789.23816142687, 35015.0, 35353.82323088368, 35494.822525887204, 35677.28645427025, 36065.098372540895, 33778.3244335107, 35029.824850875586, 35108.29783404266, 35314.293714126085, 35661.465078023335, 38827.0, 39236.0, 39245.41131883081, 39411.40882886702, 40147.39778903377, 33594.664053359156, 33638.83180584081, 34845.825770870986, 35000.474992875636, 35304.823475882455, 23718.169864054675, 24610.261692149263, 25271.620925686497, 25446.781827139173, 25493.61759573645], "value": 17931.96786853017, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.6688166250144, 588.6497533967288, 590.4567797626183, 592.4431034827262, 605.4490413723513, 22620.208292709758, 23457.0, 23665.526689466224, 23820.5235895282, 23987.64018539719, 26279.86860065697, 26895.0, 26944.191674249803, 26961.32596685084, 26965.46069078622, 6572.934270657292, 6704.832379190535, 6713.56361836479, 6730.326967303279, 6733.259341472459, 6190.78332258371, 6507.0, 6519.771807986722, 6524.0, 6749.2913244109395, 6445.291017988017, 6464.935350646504, 6478.805635830909, 6486.221653401599, 6486.610803351814, 6418.775342863, 6450.8064758057335, 6451.677416129198, 6468.450181734558, 6469.353064693493, 5954.07711804671, 5989.431004054608, 6019.488343490809, 6031.155638210654, 6037.124616930564, 28030.299242518897, 29456.0, 29618.259543511584, 29889.402211955858, 29978.85010574955, 1872.3166044393834, 1897.6204759048107, 1898.8670793044491, 1901.9619607607908, 1904.9333273335506, 18986.145623446977, 19373.32193373225, 19565.80434195654, 19641.31255406054, 19683.409497715093, 2377.23780178633, 2385.5109702510945, 2440.572899742556, 2444.413340798196, 2448.8408253463467, 138613.0, 144227.27886360502, 144880.0, 144958.0, 145281.0, 14655.85344146545, 15154.090754554541, 15176.165310908005, 15247.847521524865, 15630.374785008473, 23379.53240935172, 23419.06323747031, 23447.765522344558, 23545.41136471602, 23598.4100397488, 9500.23998080152, 9517.857232141523, 9606.135447809731, 9681.128698417177, 9683.208606407696, 8232.62953167097, 8564.357673174536, 8612.956935215409, 8762.561871906506, 8789.208971192624, 8005.679772809022, 8969.14793094668, 8985.550722463851, 9209.125133112344, 9253.953730231306, 2715.9592606110923, 2716.348076461635, 2721.877515511807, 2772.819766715157, 2805.8176218545727, 935.5930170375908, 949.6723630347519, 962.6534447598859, 974.2303580171726, 991.4101109839634, 19612.11745471457, 19761.308354207533, 23381.7661823383, 24899.751002490106, 25234.495310094062, 995.1740055753708, 1006.3257617396362, 1010.9241806864515, 1044.3055368180228, 1046.576136664652, 10566.735831604117, 11031.669049928514, 11495.252808567417, 13078.636234548549, 14300.785488217685, 470621.0, 495845.0, 516997.0, 545313.0, 545769.0, 125254.0, 128248.0, 128836.0, 132657.0, 132810.0, 1288.368699337318, 1292.4442489729317, 1304.6281809684194, 1325.555938760526, 1337.732453509302, 21327.7867221332, 21708.240211592518, 21723.456913576654, 21853.562928741656, 21969.560608787415, 61102.38897611145, 61418.385816143054, 62753.372466274755, 63136.05295920473, 64591.354086460415, 74422.62788686239, 80244.0, 80672.0, 80765.19234807577, 81039.18960810316, 16415.507534773977, 17407.303707851544, 17419.738703919706, 20589.58820823605, 21180.0, 22050.0, 23506.529869402857, 23521.52956940886, 23920.64119038181, 24157.39606509781, 18677.533061673566, 18870.433886983414, 18975.715364269272, 18997.43007709771, 19118.426447206606, 16705.749413759047, 17176.570585735455, 17548.561285967444, 17677.558061048578, 17865.106744662455, 18540.721889171404, 19249.51876203106, 19305.227790888206, 19589.118489667715, 19611.11749971226, 20326.491837704176, 20467.4883127923, 20582.485437864172, 20599.485012874797, 20789.688154677995, 18663.533411664273, 18794.71807922855, 18947.526311842314, 18955.526111847314, 19020.334288299844], "value": 12488.319887722579, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1363.5636596289187, 1408.6055904346786, 1415.0519152168047, 1419.914805111694, 1421.097603022081, 13499.865001349983, 14057.086289391182, 14097.647558811022, 14105.0, 14196.858031419708, 14029.158250504952, 14313.928430357833, 14363.138211707304, 14396.424143034292, 14457.92771036146, 2647.2587675450854, 2699.932501687454, 2701.257154282578, 2707.0, 2708.214617760847, 2403.387136280246, 2481.677381940345, 2577.2783620586174, 2667.359833639932, 2758.8758505867186, 2654.3496843273424, 2687.1535466328046, 2692.394211302461, 2694.366823796406, 2697.892084316625, 2381.130887226159, 2395.35325462126, 2408.409939564809, 2409.807145463007, 2411.1560953666253, 2503.7120731115792, 2525.6211568264725, 2548.1845809341016, 2552.34915096651, 2562.6161872588777, 13624.386902589307, 13939.09395889264, 13956.302184890716, 13961.511347102803, 13963.720725585432, 7557.206493318233, 7854.646540905615, 7876.684932602689, 7877.842443151105, 7880.448368614199, 5012.097822391968, 5062.848114556569, 5132.332796736438, 5228.424873263938, 5234.0, 1806.6747985362629, 1807.0874208524742, 1819.8999055051968, 1862.1713337564788, 1869.5139263791377, 22448.438789030242, 22658.0, 22663.660045099336, 22727.886360568093, 23204.999999999833, 6234.750609975641, 6380.138681277968, 6519.608823470607, 6589.868202636017, 6616.569922954993, 15590.844091559165, 15912.124833134289, 15984.120873351845, 16014.19929003545, 16033.358665653477, 7312.415006799443, 7455.589942553103, 7508.887366689504, 7512.887306690404, 7540.698372065166, 6778.356056174655, 6856.897146542706, 6963.1296087989, 6966.512344135963, 6971.093757811452, 4886.389201349832, 4987.850364489072, 4997.575206107449, 5050.898982020339, 5081.5426611605135, 1415.0801978713712, 1456.9708605827825, 1468.9706205875823, 1481.6962522682825, 1489.0395694776807, 879.2526352600366, 883.8364902492931, 889.5774507109201, 912.7261821453537, 929.3262384770987, 8726.345524085718, 8833.116688331243, 8925.285977121815, 8998.235150012322, 9000.414973026733, 969.2294625772528, 983.6557204978201, 1018.9949050254701, 1021.9386836789816, 1021.99489002556, 4946.802127914843, 5032.270320803536, 5107.080725469451, 5230.450802665741, 5394.433584473652, 18473.44579662612, 18521.0, 18650.81349186491, 19153.425397237825, 19184.328548501013, 18249.962520613608, 18881.905590472234, 19035.238590456505, 19246.4226073218, 19501.609967800847, 10452.059314661718, 10465.84301235482, 10606.893931060746, 10650.307729997525, 10667.573297068186, 7363.300486453778, 7739.92260077392, 7775.844483110419, 7931.60341982887, 8013.679452822055, 15570.221488925281, 16053.117078560792, 16148.515544533682, 16303.755443668591, 16337.918310408371, 15850.286737096627, 16394.098324591785, 16484.67030659356, 16901.070441125856, 17108.0, 10850.782984340427, 11507.942460287644, 11539.307641541534, 11556.47995840206, 11566.248193867372, 12886.420111094836, 13005.609831705064, 13046.934765326112, 13061.21632702041, 13069.934650326688, 10193.0, 10361.533730981973, 10938.72653183645, 10976.0, 11092.279001865176, 10399.636012739518, 10656.200784941006, 10738.14094872424, 10825.188110891557, 11029.338239705643, 9546.093121153479, 9565.61737530491, 9663.275254356093, 9698.175655069115, 9726.076022777825, 6730.25967143604, 7045.189803172496, 7046.894296585453, 7051.964740176471, 7209.134903811576, 6739.427148692416, 7193.964030179815, 7294.489385742947, 7340.706371745284, 7446.7021319146625], "value": 6345.025639329408, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [484.7511280149299, 497.3141051834281, 508.17168016133695, 517.7023211653297, 524.7900839664135, 6096.847578810526, 6152.753889844412, 6176.413240742134, 6182.381761823815, 6254.249490061194, 5741.282339707536, 5930.288365396159, 5939.287285525732, 5987.341392446828, 6080.422359875804, 4869.829555965542, 4891.559759621633, 4891.779869905863, 4899.559039686445, 4930.408350997867, 4863.2461968394955, 4876.780544875472, 4895.069936712013, 4923.9753801230945, 4955.504449555052, 4663.48701642819, 4853.301344529405, 4856.854294371175, 4870.439899411577, 4907.337509436234, 4836.564709176191, 4860.222364421693, 4898.510148985109, 4905.068037072945, 4932.186189278765, 4339.327404252346, 4343.913121737579, 4370.628496577795, 4376.190404775095, 4377.518472967971, 5933.406659334075, 5955.0, 5982.68380956189, 5984.281886173643, 5989.8802023959715, 5617.32592088952, 5640.605157638967, 5654.264945557051, 5667.688277144796, 5672.205891175197, 6314.147590075327, 6388.0, 6411.230652321751, 6457.708458308357, 6470.514711396617, 4777.904441911177, 4805.303231031481, 4836.830710925136, 4841.7337046462435, 4843.0, 6726.12560367154, 6770.898436523506, 6834.760783372558, 6857.0, 6858.862822743517, 4857.0, 4893.339399181136, 4899.975500122477, 4923.77842997066, 4946.332245146931, 4938.135826230396, 4985.925211121836, 4992.525710057539, 5023.34696489454, 5043.470435604208, 5561.304836895388, 5591.720413979285, 5609.635373700697, 5633.971770162738, 5647.717614119278, 6365.957616913293, 6378.266499352541, 6378.712957916905, 6421.871562568723, 6456.386643268882, 1658.635100277937, 1699.6600679864077, 1707.59017835721, 1708.7693161423297, 1761.3130878957218, 1365.4060483689427, 1405.6626409661608, 1452.847451017649, 1454.861788130126, 1465.7068586282787, 5573.665580065209, 5585.69278689676, 5595.8528501657065, 5604.607677462538, 5647.915281270784, 6318.0, 6379.936200638027, 6432.0, 6552.213734351908, 6602.207735071726, 5578.888422231614, 5601.131824567198, 5602.971985140048, 5657.698729292286, 5736.5410767139365, 3461.9495190627026, 3467.2372078142766, 3470.410030294856, 3497.3355062538035, 3543.4153364695053, 6352.555321127476, 6382.744690212339, 6407.775727849503, 6409.871802563923, 6410.4871610272, 6738.157730283715, 6756.628385438848, 6813.2846051163915, 6816.171288915144, 6820.658967051628, 6412.422881940648, 6421.5825971311715, 6428.871422571522, 6432.71052802625, 6462.739765745822, 6498.545101842826, 6587.571807832667, 6609.372109649576, 6657.3342665733035, 6693.598384096969, 6542.149520562487, 6568.0, 6580.276169621242, 6599.525178058228, 6645.634490103091, 6316.494680425647, 6365.185922012204, 6376.043593461018, 6417.358264173546, 6437.519370544895, 4853.126437241189, 4892.755362231803, 4904.313396124617, 4969.6272779542405, 4983.700977941335, 6600.768973085919, 6632.933670663231, 6667.2666006738245, 6684.465242780663, 6702.865942681216, 6486.740530378732, 6497.9025314619375, 6504.6015106752275, 6518.348165183634, 6522.478201743944, 6441.1626488556185, 6441.3880681335195, 6461.90147674887, 6481.805545833632, 6497.0, 6070.908936366046, 6217.533684973736, 6274.403931626488, 6375.6174629522375, 6382.042693595998, 6253.96873015632, 6347.111404403295, 6354.23749150105, 6403.583767055126, 6434.195725534309, 6085.817425477243, 6122.969385153046, 6131.509479241739, 6151.5386346025125, 6160.353162917829], "value": 4984.163033066468, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [899.2536194958185, 912.3294378631707, 915.8214148241095, 931.5388882503162, 938.9389689670173, 18081.186346614402, 18980.620387592273, 19136.13887375068, 19147.425577232687, 19215.23139074439, 20798.792012079874, 21457.0, 21598.13607455704, 21677.67483487749, 21715.3485395438, 1031.5409642709017, 1046.3930920066343, 1049.2759995603049, 1057.4184198690689, 1059.5920570580306, 1015.451656105702, 1055.1980494823952, 1055.5830446973441, 1056.1972900595565, 1058.253930978662, 1040.1730624153815, 1112.8608923884515, 1114.5430373546847, 1114.8439218509416, 1115.7489564847926, 1101.031092638473, 1102.7077824376547, 1103.0293341859192, 1103.0568863621647, 1103.7957977774097, 811.148294290998, 811.3022800391658, 812.7480481050893, 813.5769399912072, 814.0313027497259, 19312.0, 19454.22183112674, 19631.607367852564, 19833.603327933506, 19856.900715496475, 20544.28095016667, 20592.27927022547, 20603.381898543066, 20916.895415522824, 20918.0, 13758.793618095737, 14061.5078472254, 14085.64785880351, 14086.366113524917, 14188.645283867883, 3537.2218112015316, 3579.0336609115393, 3602.2435288589427, 3618.113562177257, 3619.855205791765, 123542.0, 125266.0, 127066.36466817607, 131179.99999999904, 132493.0, 14899.70200595982, 15349.846501535065, 15835.208239588204, 15945.681086378207, 15979.920100399424, 28024.0, 28179.71820281812, 28923.0, 29016.274593135342, 29035.12894613165, 8259.752207433787, 8318.0, 8376.162383761575, 8380.958095209486, 8395.286400656012, 4234.0896707207485, 4312.719673221231, 4326.264535029052, 4343.739375637472, 4345.391645169679, 5225.660332078479, 5263.526282634582, 5324.0, 5332.226827110047, 5373.973130134403, 967.0716112531908, 994.9054839790208, 1007.385494848142, 1008.9293749437469, 1010.9646162384429, 660.8281846719791, 668.6589839181955, 684.8561802021532, 705.8235441139715, 713.4043074033136, 10949.233553651167, 11108.16688748347, 11182.273152245078, 11270.323780573028, 11464.31214127155, 650.3983814971227, 670.3899451499112, 682.7200847652439, 700.0758998122429, 706.9469789765685, 4824.710517368969, 4898.951010489849, 5054.873628159326, 5058.671186372874, 5127.487251274917, 100058.499707501, 103278.48360758148, 105540.47229763801, 105767.0, 106689.0, 87431.0, 90312.0, 93950.53024734833, 94645.0, 98802.5059874696, 77580.2241977573, 82151.58924205341, 84086.0, 84213.57893210617, 86288.0, 9718.368306060083, 9719.416834989923, 9779.26655500826, 9952.800943980936, 9956.452395118338, 36023.27953440864, 37790.811045944596, 38221.80889095537, 38428.0, 39031.804840975616, 44602.3309650362, 46232.30651540297, 47619.0, 48419.51580484291, 48538.27192592043, 14935.253237337873, 15239.238038098272, 15449.459268925533, 15550.922245389153, 15747.606309842346, 16833.15834208309, 20929.89535052315, 21288.467788304795, 21614.243501477373, 21663.916804159413, 18196.363127290482, 18353.54116147107, 18866.434006979813, 18922.905385472983, 19682.409527714193, 15170.089794612359, 15358.38566457374, 17249.137543123044, 19361.32235371755, 19831.40505784829, 17022.0, 17613.82386176122, 17697.82302176962, 17758.37845675395, 17889.28442862271, 12916.806247906477, 14279.71440571162, 14464.710705786036, 14651.560453186421, 14998.2500874958, 14337.56987290383, 14349.497767578503, 14506.274686265433, 14696.191709456083, 14838.703225935637], "value": 8855.8390300214, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1542.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 109.0, 105.0, 110.0, 108.0, 110.0, 111.0, 99.0, 110.0, 111.0], "value": 107.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 127.0, 147.0, 175.0, 127.0, 150.0, 146.0, 119.0, 166.0, 119.0], "value": 142.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 163.0, 162.0, 165.0, 162.0, 168.0, 165.0, 166.0, 166.0], "value": 164.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 76.0, 182.0, 88.0, 74.0, 73.0, 76.0, 160.0, 77.0], "value": 95.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 83.0, 131.0, 79.0, 78.0, 78.0, 141.0, 78.0, 79.0, 123.0], "value": 94.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 115.0, 115.0, 115.0, 115.0, 114.0, 115.0, 114.0, 114.0], "value": 114.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 151.0, 153.0, 153.0, 150.0, 150.0, 151.0, 152.0, 150.0, 150.0], "value": 151.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 96.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 55.0, 55.0, 54.0, 53.0, 57.0, 53.0, 53.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 55.0, 55.0, 54.0, 55.0, 56.0, 54.0, 54.0], "value": 54.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 78.0, 79.0, 79.0, 77.0, 88.0, 87.0, 78.0, 85.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 129.0, 132.0, 136.0, 133.0, 139.0, 135.0, 130.0, 128.0, 165.0], "value": 137.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 179.0, 174.0, 174.0, 179.0, 176.0, 190.0, 188.0, 177.0, 184.0], "value": 180.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 69.0, 69.0, 77.0, 67.0, 71.0, 67.0, 71.0, 67.0], "value": 69.7, "unit": "score"}]}]} 09:13:10 INFO - Return code: 0 09:13:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:13:10 INFO - # TBPL SUCCESS # 09:13:10 INFO - Running post-action listener: _package_coverage_data 09:13:10 INFO - Running post-action listener: _resource_record_post_action 09:13:10 INFO - [mozharness: 2017-07-26 16:13:10.544982Z] Finished run-tests step (success) 09:13:10 INFO - Running post-run listener: _resource_record_post_run 09:13:10 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 432951296; Read time: 12; Write time: 280260 09:13:10 INFO - TinderboxPrint: CPU usage
13.1% 09:13:10 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 09:13:10 INFO - TinderboxPrint: I/O write bytes / time
432,951,296 / 280,260 09:13:10 INFO - TinderboxPrint: CPU idle
8,266.4 (86.8%) 09:13:10 INFO - TinderboxPrint: CPU user
1,202.0 (12.6%) 09:13:10 INFO - TinderboxPrint: Swap in / out
0 / 0 09:13:10 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 107630592; Read time: 0; Write time: 80996 09:13:10 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:13:10 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 325320704; Read time: 0; Write time: 199264 09:13:11 INFO - Running post-run listener: _upload_blobber_files 09:13:11 INFO - Blob upload gear active. 09:13:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:13:11 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 09:13:11 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'] 09:13:11 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 09:13:11 INFO - (blobuploader) - INFO - Open directory for files ... 09:13:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:13:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:13:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:12 INFO - (blobuploader) - INFO - Done attempting. 09:13:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:13:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:13:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:13 INFO - (blobuploader) - INFO - Done attempting. 09:13:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:13:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:13:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:13 INFO - (blobuploader) - INFO - Done attempting. 09:13:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:13:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:13:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:17 INFO - (blobuploader) - INFO - Done attempting. 09:13:17 INFO - (blobuploader) - INFO - Iteration through files over. 09:13:17 INFO - Return code: 0 09:13:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:13:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:13:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3c18974f0f390f4dfef794ee6e11d530baa8539b4d23aabc9f4dabd793af41682c77bd64f458f1dd587b541672c53e0bf7f0273e1e07f1e3a2615548bfabdac", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55edc9860424a653f6e25bc6eacd0a37b7bbf2d8b27d9142993b36a83a093ddfa3a0941a748f7aab031cfc6ab1f8a479ed998b002dcf269299898008f687944c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e46ae0cf33f683aa139af59dcf4f43727c1c88500cb059b470f0cf20b459cfe5edc23362ec4df116b49451334c6f6bdc257de3b6d47a9fee6a00126d7cfb7f18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1449609627804e6cb1ec8825e9a6ee73aeb40244bcba80e4b1df533dc1daa429e0166cb7797031f23ed3157e6ea9fbd296fca76e9effff43846a03e30f42655"} 09:13:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:13:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:13:17 INFO - Contents: 09:13:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3c18974f0f390f4dfef794ee6e11d530baa8539b4d23aabc9f4dabd793af41682c77bd64f458f1dd587b541672c53e0bf7f0273e1e07f1e3a2615548bfabdac", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55edc9860424a653f6e25bc6eacd0a37b7bbf2d8b27d9142993b36a83a093ddfa3a0941a748f7aab031cfc6ab1f8a479ed998b002dcf269299898008f687944c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e46ae0cf33f683aa139af59dcf4f43727c1c88500cb059b470f0cf20b459cfe5edc23362ec4df116b49451334c6f6bdc257de3b6d47a9fee6a00126d7cfb7f18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1449609627804e6cb1ec8825e9a6ee73aeb40244bcba80e4b1df533dc1daa429e0166cb7797031f23ed3157e6ea9fbd296fca76e9effff43846a03e30f42655"} 09:13:17 INFO - Running post-run listener: copy_logs_to_upload_dir 09:13:17 INFO - Copying logs to upload dir... 09:13:17 INFO - mkdir: /builds/slave/test/build/upload/logs 09:13:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.719182 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-07-26 09:13:16.197445) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-26 09:13:16.204703) ========= 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=523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280 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/e3c18974f0f390f4dfef794ee6e11d530baa8539b4d23aabc9f4dabd793af41682c77bd64f458f1dd587b541672c53e0bf7f0273e1e07f1e3a2615548bfabdac", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55edc9860424a653f6e25bc6eacd0a37b7bbf2d8b27d9142993b36a83a093ddfa3a0941a748f7aab031cfc6ab1f8a479ed998b002dcf269299898008f687944c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e46ae0cf33f683aa139af59dcf4f43727c1c88500cb059b470f0cf20b459cfe5edc23362ec4df116b49451334c6f6bdc257de3b6d47a9fee6a00126d7cfb7f18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1449609627804e6cb1ec8825e9a6ee73aeb40244bcba80e4b1df533dc1daa429e0166cb7797031f23ed3157e6ea9fbd296fca76e9effff43846a03e30f42655"} build_url:https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011276 build_url: 'https://queue.taskcluster.net/v1/task/McIpE5nYSU6x4xohkK7ZTQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3c18974f0f390f4dfef794ee6e11d530baa8539b4d23aabc9f4dabd793af41682c77bd64f458f1dd587b541672c53e0bf7f0273e1e07f1e3a2615548bfabdac", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55edc9860424a653f6e25bc6eacd0a37b7bbf2d8b27d9142993b36a83a093ddfa3a0941a748f7aab031cfc6ab1f8a479ed998b002dcf269299898008f687944c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e46ae0cf33f683aa139af59dcf4f43727c1c88500cb059b470f0cf20b459cfe5edc23362ec4df116b49451334c6f6bdc257de3b6d47a9fee6a00126d7cfb7f18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1449609627804e6cb1ec8825e9a6ee73aeb40244bcba80e4b1df533dc1daa429e0166cb7797031f23ed3157e6ea9fbd296fca76e9effff43846a03e30f42655"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-26 09:13:16.228722) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-26 09:13:16.229055) ========= 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=523ca41ca1e0623de333ad52000001bf-1501064447.52523-1065931280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004529 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-26 09:13:16.314083) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-26 09:13:16.317611) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-26 09:13:16.317936) ========= ========= Total master_lag: 0.17 =========