builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-050 starttime: 1506121164.81 results: success (0) revision: 61e58a7d800b8a84d3da7c3db977830e206428a3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-22 15:59:24.809775) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-22 15:59:24.810261) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-22 15:59:24.810670) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-22 15:59:24.841745) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 15:59:24.842184) ========= 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=ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-22 15:59:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 41.8M=0s 2017-09-22 15:59:24 (41.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092793 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 15:59:24.968174) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 15:59:24.968494) ========= 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=ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016426 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 15:59:25.030667) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-22 15:59:25.030969) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 61e58a7d800b8a84d3da7c3db977830e206428a3 --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 61e58a7d800b8a84d3da7c3db977830e206428a3 --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=ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-22 15:59:24,996 truncating revision to first 12 chars 2017-09-22 15:59:24,996 Setting DEBUG logging. 2017-09-22 15:59:24,996 attempt 1/10 2017-09-22 15:59:24,996 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/61e58a7d800b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-22 15:59:25,739 unpacking tar archive at: mozilla-central-61e58a7d800b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.033243 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-22 15:59:26.088143) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-22 15:59:26.088430) ========= script_repo_revision: 61e58a7d800b8a84d3da7c3db977830e206428a3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-22 15:59:26.091848) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-22 15:59:26.092417) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-22 15:59:26.106933) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-09-22 15:59:26.107326) ========= /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=ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:59:26 INFO - MultiFileLogger online at 20170922 15:59:26 in /builds/slave/test 15:59:26 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 15:59:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:59:26 INFO - {'append_to_log': False, 15:59:26 INFO - 'base_work_dir': '/builds/slave/test', 15:59:26 INFO - 'blob_upload_branch': 'Firefox', 15:59:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:59:26 INFO - 'branch': 'Firefox', 15:59:26 INFO - 'buildbot_json_path': 'buildprops.json', 15:59:26 INFO - 'code_coverage': False, 15:59:26 INFO - 'config_files': ('talos/linux_config.py',), 15:59:26 INFO - 'default_actions': ('clobber', 15:59:26 INFO - 'read-buildbot-config', 15:59:26 INFO - 'download-and-extract', 15:59:26 INFO - 'populate-webroot', 15:59:26 INFO - 'create-virtualenv', 15:59:26 INFO - 'install', 15:59:26 INFO - 'setup-mitmproxy', 15:59:26 INFO - 'run-tests'), 15:59:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:59:26 INFO - 'disable_ccov_upload': False, 15:59:26 INFO - 'disable_stylo': False, 15:59:26 INFO - 'download_minidump_stackwalk': True, 15:59:26 INFO - 'download_symbols': 'ondemand', 15:59:26 INFO - 'enable_stylo': False, 15:59:26 INFO - 'enable_webrender': False, 15:59:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:59:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:59:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:59:26 INFO - 'gecko_profile': False, 15:59:26 INFO - 'gecko_profile_interval': 0, 15:59:26 INFO - 'installer_path': 'installer.exe', 15:59:26 INFO - 'log_level': 'info', 15:59:26 INFO - 'log_name': 'talos', 15:59:26 INFO - 'log_to_console': True, 15:59:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:59:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:59:26 INFO - 'opt_config_files': (), 15:59:26 INFO - 'pip_index': False, 15:59:26 INFO - 'suite': 'dromaeojs-e10s', 15:59:26 INFO - 'system_bits': '32', 15:59:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:59:26 INFO - 'title': 'talos-linux64-ix-050', 15:59:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:59:26 INFO - 'use_talos_json': True, 15:59:26 INFO - 'verify': 'False', 15:59:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:59:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:59:26 INFO - 'work_dir': 'build'} 15:59:26 INFO - [mozharness: 2017-09-22 22:59:26.212284Z] Running clobber step. 15:59:26 INFO - Running pre-action listener: _resource_record_pre_action 15:59:26 INFO - Running main action method: clobber 15:59:26 INFO - rmtree: /builds/slave/test/build 15:59:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:59:27 INFO - Running post-action listener: _resource_record_post_action 15:59:27 INFO - [mozharness: 2017-09-22 22:59:27.745479Z] Finished clobber step (success) 15:59:27 INFO - [mozharness: 2017-09-22 22:59:27.745577Z] Running read-buildbot-config step. 15:59:27 INFO - Running pre-action listener: _resource_record_pre_action 15:59:27 INFO - Running main action method: read_buildbot_config 15:59:27 INFO - Using buildbot properties: 15:59:27 INFO - { 15:59:27 INFO - "project": "", 15:59:27 INFO - "product": "firefox", 15:59:27 INFO - "who": "kwierso@gmail.com", 15:59:27 INFO - "installer_path": "public/build/target.tar.bz2", 15:59:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:59:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:59:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 15:59:27 INFO - "stage_platform": "linux64-pgo", 15:59:27 INFO - "basedir": "/builds/slave/test", 15:59:27 INFO - "buildnumber": 196, 15:59:27 INFO - "platform": "ubuntu64_hw", 15:59:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:59:27 INFO - "slavebuilddir": "test", 15:59:27 INFO - "taskId": "L48DNzZHTBikfHzSmi7RqA", 15:59:27 INFO - "branch": "mozilla-central", 15:59:27 INFO - "script_repo_revision": "production", 15:59:27 INFO - "revision": "61e58a7d800b8a84d3da7c3db977830e206428a3", 15:59:27 INFO - "slavename": "talos-linux64-ix-050", 15:59:27 INFO - "repo_path": "mozilla-central" 15:59:27 INFO - } 15:59:27 INFO - Finding installer, test and symbols from parent task. 15:59:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L48DNzZHTBikfHzSmi7RqA'}, attempt #1 15:59:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L48DNzZHTBikfHzSmi7RqA'}, attempt #1 15:59:28 INFO - Task dependencies: dkFSWy0DRHCR9Q0gzCA1Rg 15:59:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dkFSWy0DRHCR9Q0gzCA1Rg'}, attempt #1 15:59:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dkFSWy0DRHCR9Q0gzCA1Rg/artifacts/public/build/target.tar.bz2 15:59:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/dkFSWy0DRHCR9Q0gzCA1Rg/artifacts/public/build/target.test_packages.json 15:59:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dkFSWy0DRHCR9Q0gzCA1Rg/artifacts/public/build/target.crashreporter-symbols.zip 15:59:29 INFO - Running post-action listener: _resource_record_post_action 15:59:29 INFO - [mozharness: 2017-09-22 22:59:29.223726Z] Finished read-buildbot-config step (success) 15:59:29 INFO - [mozharness: 2017-09-22 22:59:29.223943Z] Running download-and-extract step. 15:59:29 INFO - Running pre-action listener: _resource_record_pre_action 15:59:29 INFO - Running main action method: download_and_extract 15:59:29 INFO - mkdir: /builds/slave/test/build/tests 15:59:29 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')]} 15:59:29 INFO - trying https://queue.taskcluster.net/v1/task/dkFSWy0DRHCR9Q0gzCA1Rg/artifacts/public/build/target.test_packages.json 15:59:29 INFO - Downloading https://queue.taskcluster.net/v1/task/dkFSWy0DRHCR9Q0gzCA1Rg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:59:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dkFSWy0DRHCR9Q0gzCA1Rg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:59:29 INFO - Downloaded 989 bytes. 15:59:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:59:29 INFO - Using the following test package requirements: 15:59:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:59:29 INFO - u'common': [u'target.common.tests.zip'], 15:59:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:59:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:59:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:59:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:59:29 INFO - u'mozbase': [u'target.common.tests.zip'], 15:59:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:59:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:59:29 INFO - u'web-platform': [u'target.common.tests.zip', 15:59:29 INFO - u'target.web-platform.tests.tar.gz'], 15:59:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:59:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 15:59:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dkFSWy0DRHCR9Q0gzCA1Rg/artifacts/public/build/target.common.tests.zip 15:59:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dkFSWy0DRHCR9Q0gzCA1Rg/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:59:29 INFO - Fetch https://queue.taskcluster.net/v1/task/dkFSWy0DRHCR9Q0gzCA1Rg/artifacts/public/build/target.common.tests.zip into memory 15:59:31 INFO - Content-Length response header: 55335803 15:59:31 INFO - Bytes received: 55335803 15:59:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dkFSWy0DRHCR9Q0gzCA1Rg/artifacts/public/build/target.talos.tests.zip 15:59:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dkFSWy0DRHCR9Q0gzCA1Rg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:59:42 INFO - Fetch https://queue.taskcluster.net/v1/task/dkFSWy0DRHCR9Q0gzCA1Rg/artifacts/public/build/target.talos.tests.zip into memory 15:59:42 INFO - Content-Length response header: 14513823 15:59:42 INFO - Bytes received: 14513823 15:59:43 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')]} 15:59:43 INFO - trying https://queue.taskcluster.net/v1/task/dkFSWy0DRHCR9Q0gzCA1Rg/artifacts/public/build/target.tar.bz2 15:59:43 INFO - Downloading https://queue.taskcluster.net/v1/task/dkFSWy0DRHCR9Q0gzCA1Rg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:59:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dkFSWy0DRHCR9Q0gzCA1Rg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:59:44 INFO - Downloaded 62412006 bytes. 15:59:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dkFSWy0DRHCR9Q0gzCA1Rg/artifacts/public/build/target.tar.bz2 15:59:44 INFO - mkdir: /builds/slave/test/properties 15:59:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:59:44 INFO - Writing to file /builds/slave/test/properties/build_url 15:59:44 INFO - Contents: 15:59:44 INFO - build_url:https://queue.taskcluster.net/v1/task/dkFSWy0DRHCR9Q0gzCA1Rg/artifacts/public/build/target.tar.bz2 15:59:44 INFO - Running post-action listener: _resource_record_post_action 15:59:44 INFO - Running post-action listener: find_tests_for_verification 15:59:44 INFO - Running post-action listener: set_extra_try_arguments 15:59:44 INFO - [mozharness: 2017-09-22 22:59:44.594927Z] Finished download-and-extract step (success) 15:59:44 INFO - [mozharness: 2017-09-22 22:59:44.595381Z] Running populate-webroot step. 15:59:44 INFO - Running pre-action listener: _resource_record_pre_action 15:59:44 INFO - Running main action method: populate_webroot 15:59:44 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:59:44 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:59:44 INFO - 'tests': ['tresize', 15:59:44 INFO - 'tcanvasmark']}, 15:59:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:59:44 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:59:44 INFO - 'tests': ['dromaeo_css', 15:59:44 INFO - 'kraken']}, 15:59:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:59:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:59:44 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:59:44 INFO - 'talos_options': ['--disable-stylo'], 15:59:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:59:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:59:44 INFO - 'tests': ['damp', 'tps']}, 15:59:44 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:59:44 INFO - 'talos_options': ['--disable-stylo'], 15:59:44 INFO - 'tests': ['damp', 'tps']}, 15:59:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:59:44 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:59:44 INFO - 'tests': ['dromaeo_dom']}, 15:59:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:59:44 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:59:44 INFO - 'tests': ['basic_compositor_video', 15:59:44 INFO - 'glvideo']}, 15:59:44 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:59:44 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:59:44 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:59:44 INFO - 'talos_options': ['--disable-stylo'], 15:59:44 INFO - 'tests': ['ts_paint_webext', 15:59:44 INFO - 'tp5o_webext']}, 15:59:44 INFO - 'other-e10s': {'tests': ['a11yr', 15:59:44 INFO - 'ts_paint', 15:59:44 INFO - 'tpaint', 15:59:44 INFO - 'sessionrestore', 15:59:44 INFO - 'sessionrestore_many_windows', 15:59:44 INFO - 'sessionrestore_no_auto_restore', 15:59:44 INFO - 'tabpaint']}, 15:59:44 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:59:44 INFO - 'tests': ['a11yr', 15:59:44 INFO - 'ts_paint', 15:59:44 INFO - 'tpaint', 15:59:44 INFO - 'sessionrestore', 15:59:44 INFO - 'sessionrestore_many_windows', 15:59:44 INFO - 'sessionrestore_no_auto_restore', 15:59:44 INFO - 'tabpaint']}, 15:59:44 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 15:59:44 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 15:59:44 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:59:44 INFO - 'tests': ['perf_reftest_singletons']}, 15:59:44 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:59:44 INFO - 'tests': ['perf_reftest']}, 15:59:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:59:44 INFO - 'tsvgr_opacity', 15:59:44 INFO - 'tart', 15:59:44 INFO - 'tscrollx', 15:59:44 INFO - 'tsvg_static']}, 15:59:44 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:59:44 INFO - 'tests': ['tsvgx', 15:59:44 INFO - 'tsvgr_opacity', 15:59:44 INFO - 'tart', 15:59:44 INFO - 'tscrollx', 15:59:44 INFO - 'tsvg_static']}, 15:59:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:59:44 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:59:44 INFO - 'talos_options': ['--disable-stylo'], 15:59:44 INFO - 'tests': ['tp5o']}, 15:59:44 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:59:44 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:59:44 INFO - 'talos_options': ['--mitmproxy', 15:59:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:59:44 INFO - '--firstNonBlankPaint'], 15:59:44 INFO - 'tests': ['tp6_google', 15:59:44 INFO - 'tp6_youtube', 15:59:44 INFO - 'tp6_amazon', 15:59:44 INFO - 'tp6_facebook']}, 15:59:44 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:59:44 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:59:44 INFO - 'talos_options': ['--disable-stylo', 15:59:44 INFO - '--mitmproxy', 15:59:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:59:44 INFO - '--firstNonBlankPaint'], 15:59:44 INFO - 'tests': ['tp6_google', 15:59:44 INFO - 'tp6_youtube', 15:59:44 INFO - 'tp6_amazon', 15:59:44 INFO - 'tp6_facebook']}, 15:59:44 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:59:44 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:59:44 INFO - 'talos_options': ['--stylo-threads=1', 15:59:44 INFO - '--mitmproxy', 15:59:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:59:44 INFO - '--firstNonBlankPaint'], 15:59:44 INFO - 'tests': ['tp6_google', 15:59:44 INFO - 'tp6_youtube', 15:59:44 INFO - 'tp6_amazon', 15:59:44 INFO - 'tp6_facebook']}, 15:59:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:59:44 INFO - 'talos_options': ['--xperf_path', 15:59:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:59:44 INFO - 'tests': ['tp5n']}, 15:59:44 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:59:44 INFO - 'talos_options': ['--disable-stylo', 15:59:44 INFO - '--xperf_path', 15:59:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:59:44 INFO - 'tests': ['tp5n']}}} 15:59:44 INFO - Running post-action listener: _resource_record_post_action 15:59:44 INFO - [mozharness: 2017-09-22 22:59:44.618543Z] Finished populate-webroot step (success) 15:59:44 INFO - [mozharness: 2017-09-22 22:59:44.618632Z] Running create-virtualenv step. 15:59:44 INFO - Running pre-action listener: _resource_record_pre_action 15:59:44 INFO - Running main action method: create_virtualenv 15:59:44 INFO - Creating virtualenv /builds/slave/test/build/venv 15:59:44 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:59:44 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:59:44 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:59:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:59:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:59:45 INFO - Installing distribute.............................................................................................................................................................................................done. 15:59:45 INFO - Installing pip...............done. 15:59:45 INFO - Return code: 0 15:59:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:59:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:59:45 INFO - Reading from file tmpfile_stdout 15:59:45 INFO - Output received: 15:59:45 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:59:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:59:45 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')]} 15:59:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:45 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')]} 15:59:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:45 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 0x15441f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b19c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18aed60>, '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 0x18afa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18afef0>, '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': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', '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 15:59:45 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 15:59:45 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 15:59:45 INFO - Using env: {'DISPLAY': ':0', 15:59:45 INFO - 'HOME': '/home/cltbld', 15:59:45 INFO - 'LANG': 'en_US.UTF-8', 15:59:45 INFO - 'LANGUAGE': 'en_US:en', 15:59:45 INFO - 'LOGNAME': 'cltbld', 15:59:45 INFO - 'MAIL': '/var/mail/cltbld', 15:59:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:45 INFO - 'MOZ_NO_REMOTE': '1', 15:59:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:45 INFO - 'NO_EM_RESTART': '1', 15:59:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:45 INFO - 'PWD': '/builds/slave/test', 15:59:45 INFO - 'SHELL': '/bin/bash', 15:59:45 INFO - 'SHLVL': '1', 15:59:45 INFO - 'TERM': 'linux', 15:59:45 INFO - 'TMOUT': '86400', 15:59:45 INFO - 'USER': 'cltbld', 15:59:45 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', 15:59:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:45 INFO - '_': '/tools/buildbot/bin/python'} 15:59:46 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:59:46 INFO - Downloading/unpacking pip>=1.5 15:59:46 INFO - Running setup.py egg_info for package pip 15:59:46 INFO - warning: no files found matching 'pip/cacert.pem' 15:59:46 INFO - warning: no files found matching '*.html' under directory 'docs' 15:59:46 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:59:46 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:59:46 INFO - Installing collected packages: pip 15:59:46 INFO - Found existing installation: pip 1.1 15:59:46 INFO - Uninstalling pip: 15:59:46 INFO - Successfully uninstalled pip 15:59:46 INFO - Running setup.py install for pip 15:59:46 INFO - warning: no files found matching 'pip/cacert.pem' 15:59:46 INFO - warning: no files found matching '*.html' under directory 'docs' 15:59:46 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:59:46 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:59:46 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:59:46 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:59:46 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:59:46 INFO - Successfully installed pip 15:59:46 INFO - Cleaning up... 15:59:46 INFO - Return code: 0 15:59:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:59:46 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')]} 15:59:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:46 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')]} 15:59:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:46 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 0x15441f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b19c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18aed60>, '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 0x18afa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18afef0>, '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': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', '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 15:59:46 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 15:59:46 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 15:59:46 INFO - Using env: {'DISPLAY': ':0', 15:59:46 INFO - 'HOME': '/home/cltbld', 15:59:46 INFO - 'LANG': 'en_US.UTF-8', 15:59:46 INFO - 'LANGUAGE': 'en_US:en', 15:59:46 INFO - 'LOGNAME': 'cltbld', 15:59:46 INFO - 'MAIL': '/var/mail/cltbld', 15:59:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:46 INFO - 'MOZ_NO_REMOTE': '1', 15:59:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:46 INFO - 'NO_EM_RESTART': '1', 15:59:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:46 INFO - 'PWD': '/builds/slave/test', 15:59:46 INFO - 'SHELL': '/bin/bash', 15:59:46 INFO - 'SHLVL': '1', 15:59:46 INFO - 'TERM': 'linux', 15:59:46 INFO - 'TMOUT': '86400', 15:59:46 INFO - 'USER': 'cltbld', 15:59:46 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', 15:59:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:46 INFO - '_': '/tools/buildbot/bin/python'} 15:59:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:47 INFO - Downloading/unpacking psutil>=3.1.1 15:59:47 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 15:59:47 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 15:59:47 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 15:59:47 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 15:59:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:59:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:59:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:59:48 INFO - Installing collected packages: psutil 15:59:48 INFO - Running setup.py install for psutil 15:59:48 INFO - building 'psutil._psutil_linux' extension 15:59:48 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 15:59:48 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 15:59:48 INFO - building 'psutil._psutil_posix' extension 15:59:48 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 15:59:48 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 15:59:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:59:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:59:48 INFO - Successfully installed psutil 15:59:48 INFO - Cleaning up... 15:59:48 INFO - Return code: 0 15:59:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:59:48 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')]} 15:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:48 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')]} 15:59:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:48 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 0x15441f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b19c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18aed60>, '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 0x18afa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18afef0>, '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': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', '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 15:59:48 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 15:59:48 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 15:59:48 INFO - Using env: {'DISPLAY': ':0', 15:59:48 INFO - 'HOME': '/home/cltbld', 15:59:48 INFO - 'LANG': 'en_US.UTF-8', 15:59:48 INFO - 'LANGUAGE': 'en_US:en', 15:59:48 INFO - 'LOGNAME': 'cltbld', 15:59:48 INFO - 'MAIL': '/var/mail/cltbld', 15:59:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:48 INFO - 'MOZ_NO_REMOTE': '1', 15:59:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:48 INFO - 'NO_EM_RESTART': '1', 15:59:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:48 INFO - 'PWD': '/builds/slave/test', 15:59:48 INFO - 'SHELL': '/bin/bash', 15:59:48 INFO - 'SHLVL': '1', 15:59:48 INFO - 'TERM': 'linux', 15:59:48 INFO - 'TMOUT': '86400', 15:59:48 INFO - 'USER': 'cltbld', 15:59:48 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', 15:59:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:48 INFO - '_': '/tools/buildbot/bin/python'} 15:59:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:59:49 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 15:59:49 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 15:59:49 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 15:59:49 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 15:59:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:59:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:59:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:59:50 INFO - Installing collected packages: mozsystemmonitor 15:59:50 INFO - Running setup.py install for mozsystemmonitor 15:59:50 INFO - Successfully installed mozsystemmonitor 15:59:50 INFO - Cleaning up... 15:59:51 INFO - Return code: 0 15:59:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:59:51 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')]} 15:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:51 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')]} 15:59:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:51 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 0x15441f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b19c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18aed60>, '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 0x18afa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18afef0>, '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': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', '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 15:59:51 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 15:59:51 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 15:59:51 INFO - Using env: {'DISPLAY': ':0', 15:59:51 INFO - 'HOME': '/home/cltbld', 15:59:51 INFO - 'LANG': 'en_US.UTF-8', 15:59:51 INFO - 'LANGUAGE': 'en_US:en', 15:59:51 INFO - 'LOGNAME': 'cltbld', 15:59:51 INFO - 'MAIL': '/var/mail/cltbld', 15:59:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:51 INFO - 'MOZ_NO_REMOTE': '1', 15:59:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:51 INFO - 'NO_EM_RESTART': '1', 15:59:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:51 INFO - 'PWD': '/builds/slave/test', 15:59:51 INFO - 'SHELL': '/bin/bash', 15:59:51 INFO - 'SHLVL': '1', 15:59:51 INFO - 'TERM': 'linux', 15:59:51 INFO - 'TMOUT': '86400', 15:59:51 INFO - 'USER': 'cltbld', 15:59:51 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', 15:59:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:51 INFO - '_': '/tools/buildbot/bin/python'} 15:59:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:51 INFO - Downloading/unpacking jsonschema==2.5.1 15:59:51 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 15:59:51 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 15:59:51 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 15:59:51 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 15:59:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:59:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:59:52 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 15:59:52 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 15:59:52 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 15:59:52 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 15:59:52 INFO - Downloading functools32-3.2.3-2.tar.gz 15:59:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:59:52 INFO - warning: no files found matching '*.txt' 15:59:52 INFO - no previously-included directories found matching 'build' 15:59:52 INFO - no previously-included directories found matching 'dist' 15:59:52 INFO - no previously-included directories found matching '.git*' 15:59:52 INFO - Installing collected packages: jsonschema, functools32 15:59:52 INFO - Running setup.py install for functools32 15:59:52 INFO - warning: no files found matching '*.txt' 15:59:52 INFO - no previously-included directories found matching 'build' 15:59:52 INFO - no previously-included directories found matching 'dist' 15:59:52 INFO - no previously-included directories found matching '.git*' 15:59:52 INFO - Successfully installed jsonschema functools32 15:59:52 INFO - Cleaning up... 15:59:53 INFO - Return code: 0 15:59:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:59: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')]} 15:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59: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')]} 15:59:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:53 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 0x15441f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b19c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18aed60>, '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 0x18afa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18afef0>, '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': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', '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 15:59:53 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 15:59:53 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 15:59:53 INFO - Using env: {'DISPLAY': ':0', 15:59:53 INFO - 'HOME': '/home/cltbld', 15:59:53 INFO - 'LANG': 'en_US.UTF-8', 15:59:53 INFO - 'LANGUAGE': 'en_US:en', 15:59:53 INFO - 'LOGNAME': 'cltbld', 15:59:53 INFO - 'MAIL': '/var/mail/cltbld', 15:59:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:53 INFO - 'MOZ_NO_REMOTE': '1', 15:59:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:53 INFO - 'NO_EM_RESTART': '1', 15:59:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:53 INFO - 'PWD': '/builds/slave/test', 15:59:53 INFO - 'SHELL': '/bin/bash', 15:59:53 INFO - 'SHLVL': '1', 15:59:53 INFO - 'TERM': 'linux', 15:59:53 INFO - 'TMOUT': '86400', 15:59:53 INFO - 'USER': 'cltbld', 15:59:53 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', 15:59:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:53 INFO - '_': '/tools/buildbot/bin/python'} 15:59:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:59:53 INFO - Cleaning up... 15:59:53 INFO - Return code: 0 15:59:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:59: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')]} 15:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59: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')]} 15:59:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:53 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 0x15441f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b19c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18aed60>, '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 0x18afa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18afef0>, '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': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', '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 15:59:53 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 15:59:53 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 15:59:53 INFO - Using env: {'DISPLAY': ':0', 15:59:53 INFO - 'HOME': '/home/cltbld', 15:59:53 INFO - 'LANG': 'en_US.UTF-8', 15:59:53 INFO - 'LANGUAGE': 'en_US:en', 15:59:53 INFO - 'LOGNAME': 'cltbld', 15:59:53 INFO - 'MAIL': '/var/mail/cltbld', 15:59:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:53 INFO - 'MOZ_NO_REMOTE': '1', 15:59:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:53 INFO - 'NO_EM_RESTART': '1', 15:59:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:53 INFO - 'PWD': '/builds/slave/test', 15:59:53 INFO - 'SHELL': '/bin/bash', 15:59:53 INFO - 'SHLVL': '1', 15:59:53 INFO - 'TERM': 'linux', 15:59:53 INFO - 'TMOUT': '86400', 15:59:53 INFO - 'USER': 'cltbld', 15:59:53 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', 15:59:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:53 INFO - '_': '/tools/buildbot/bin/python'} 15:59:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:53 INFO - Downloading/unpacking blobuploader==1.2.4 15:59:53 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 15:59:53 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 15:59:53 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 15:59:53 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 15:59:54 INFO - Downloading blobuploader-1.2.4.tar.gz 15:59:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:59:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:59:54 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 15:59:54 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 15:59:54 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 15:59:54 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 15:59:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:59:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:59:54 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 15:59:54 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 15:59:54 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 15:59:54 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 15:59:55 INFO - Downloading docopt-0.6.1.tar.gz 15:59:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:59:55 INFO - Installing collected packages: blobuploader, requests, docopt 15:59:55 INFO - Running setup.py install for blobuploader 15:59:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:59:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:59:55 INFO - Running setup.py install for requests 15:59:55 INFO - Running setup.py install for docopt 15:59:55 INFO - Successfully installed blobuploader requests docopt 15:59:55 INFO - Cleaning up... 15:59:55 INFO - Return code: 0 15:59:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:59:55 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')]} 15:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:55 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')]} 15:59:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:55 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 0x15441f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b19c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18aed60>, '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 0x18afa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18afef0>, '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': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', '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 15:59:55 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 15:59:55 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 15:59:55 INFO - Using env: {'DISPLAY': ':0', 15:59:55 INFO - 'HOME': '/home/cltbld', 15:59:55 INFO - 'LANG': 'en_US.UTF-8', 15:59:55 INFO - 'LANGUAGE': 'en_US:en', 15:59:55 INFO - 'LOGNAME': 'cltbld', 15:59:55 INFO - 'MAIL': '/var/mail/cltbld', 15:59:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:55 INFO - 'MOZ_NO_REMOTE': '1', 15:59:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:55 INFO - 'NO_EM_RESTART': '1', 15:59:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:55 INFO - 'PWD': '/builds/slave/test', 15:59:55 INFO - 'SHELL': '/bin/bash', 15:59:55 INFO - 'SHLVL': '1', 15:59:55 INFO - 'TERM': 'linux', 15:59:55 INFO - 'TMOUT': '86400', 15:59:55 INFO - 'USER': 'cltbld', 15:59:55 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', 15:59:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:55 INFO - '_': '/tools/buildbot/bin/python'} 15:59:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:59:55 INFO - Running setup.py (path:/tmp/pip-FN2Vi3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:59:56 INFO - Running setup.py (path:/tmp/pip-TytuVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:59:56 INFO - Running setup.py (path:/tmp/pip-NNP1xV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:59:56 INFO - Running setup.py (path:/tmp/pip-d9mpYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:59:56 INFO - Running setup.py (path:/tmp/pip-slvZYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:59:56 INFO - Running setup.py (path:/tmp/pip-_JpqX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:59:56 INFO - Running setup.py (path:/tmp/pip-Ix4bh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:59:56 INFO - Running setup.py (path:/tmp/pip-GLz28s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:59:56 INFO - Running setup.py (path:/tmp/pip-gHqsPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:59:56 INFO - Running setup.py (path:/tmp/pip-BwcElR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:59:56 INFO - Running setup.py (path:/tmp/pip-sXJue0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:59:56 INFO - Running setup.py (path:/tmp/pip-qoSISq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:59:56 INFO - Running setup.py (path:/tmp/pip-VAEOjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:59:56 INFO - Running setup.py (path:/tmp/pip-5LvMyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:59:56 INFO - Running setup.py (path:/tmp/pip-zONOMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:59:57 INFO - Running setup.py (path:/tmp/pip-Wef1hk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:59:57 INFO - Running setup.py (path:/tmp/pip-6NSlQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:59:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:59:57 INFO - Running setup.py install for manifestparser 15:59:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:59:57 INFO - Running setup.py install for mozcrash 15:59:57 INFO - Running setup.py install for mozdebug 15:59:57 INFO - Running setup.py install for mozdevice 15:59:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:59:57 INFO - Running setup.py install for mozfile 15:59:57 INFO - Running setup.py install for mozhttpd 15:59:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:59:57 INFO - Running setup.py install for mozinfo 15:59:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:59:57 INFO - Running setup.py install for mozInstall 15:59:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:59:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:59:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:59:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:59:57 INFO - Running setup.py install for mozleak 15:59:58 INFO - Running setup.py install for mozlog 15:59:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:59:58 INFO - Running setup.py install for moznetwork 15:59:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:59:58 INFO - Running setup.py install for mozprocess 15:59:58 INFO - Running setup.py install for mozprofile 15:59:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:59:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:59:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:59:58 INFO - Running setup.py install for mozrunner 15:59:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:59:58 INFO - Running setup.py install for mozscreenshot 15:59:58 INFO - Running setup.py install for moztest 15:59:58 INFO - Running setup.py install for mozversion 15:59:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:59:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:59:58 INFO - Cleaning up... 15:59:58 INFO - Return code: 0 15:59:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:59: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')]} 15:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59: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')]} 15:59:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:58 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 0x15441f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b19c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18aed60>, '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 0x18afa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18afef0>, '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': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', '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 15:59:58 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 15:59:58 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 15:59:58 INFO - Using env: {'DISPLAY': ':0', 15:59:58 INFO - 'HOME': '/home/cltbld', 15:59:58 INFO - 'LANG': 'en_US.UTF-8', 15:59:58 INFO - 'LANGUAGE': 'en_US:en', 15:59:58 INFO - 'LOGNAME': 'cltbld', 15:59:58 INFO - 'MAIL': '/var/mail/cltbld', 15:59:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:58 INFO - 'MOZ_NO_REMOTE': '1', 15:59:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:58 INFO - 'NO_EM_RESTART': '1', 15:59:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:58 INFO - 'PWD': '/builds/slave/test', 15:59:58 INFO - 'SHELL': '/bin/bash', 15:59:58 INFO - 'SHLVL': '1', 15:59:58 INFO - 'TERM': 'linux', 15:59:58 INFO - 'TMOUT': '86400', 15:59:58 INFO - 'USER': 'cltbld', 15:59:58 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', 15:59:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:58 INFO - '_': '/tools/buildbot/bin/python'} 15:59:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:59:59 INFO - Running setup.py (path:/tmp/pip-HAfAne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:59:59 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)) 15:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:59:59 INFO - Running setup.py (path:/tmp/pip-UvGewL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:59:59 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)) 15:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:59:59 INFO - Running setup.py (path:/tmp/pip-8RhHDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:59:59 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)) 15:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:59:59 INFO - Running setup.py (path:/tmp/pip-QMy5Rv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:59:59 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)) 15:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:59:59 INFO - Running setup.py (path:/tmp/pip-g7L1CB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:59:59 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)) 15:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:59:59 INFO - Running setup.py (path:/tmp/pip-eFr5SD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:59:59 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)) 15:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:59:59 INFO - Running setup.py (path:/tmp/pip-afjegq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:59:59 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)) 15:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:59:59 INFO - Running setup.py (path:/tmp/pip-Nzq4hG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:59:59 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)) 15:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:59:59 INFO - Running setup.py (path:/tmp/pip-Ewpbm1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:59:59 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)) 15:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:59:59 INFO - Running setup.py (path:/tmp/pip-VGl0X8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:59:59 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)) 15:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:59:59 INFO - Running setup.py (path:/tmp/pip-7VfEsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:59:59 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)) 15:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:59:59 INFO - Running setup.py (path:/tmp/pip-1UEmyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:00:00 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)) 16:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:00:00 INFO - Running setup.py (path:/tmp/pip-X9wvFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:00:00 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)) 16:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:00:00 INFO - Running setup.py (path:/tmp/pip-KqcbNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:00:00 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)) 16:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:00:00 INFO - Running setup.py (path:/tmp/pip-FoKD1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:00:00 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)) 16:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:00:00 INFO - Running setup.py (path:/tmp/pip-MIY_9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:00:00 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)) 16:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:00:00 INFO - Running setup.py (path:/tmp/pip-3wjfMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:00:00 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)) 16:00:00 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)) 16:00:00 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)) 16:00:00 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)) 16:00:00 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)) 16:00:00 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)) 16:00:00 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)) 16:00:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:00: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 16:00: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 16:00: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 16:00: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 16:00:01 INFO - Downloading blessings-1.6.tar.gz 16:00:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:00:01 INFO - Installing collected packages: blessings 16:00:01 INFO - Running setup.py install for blessings 16:00:01 INFO - Successfully installed blessings 16:00:01 INFO - Cleaning up... 16:00:01 INFO - Return code: 0 16:00:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:00:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:00:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:00:01 INFO - Reading from file tmpfile_stdout 16:00:01 INFO - Current package versions: 16:00:01 INFO - argparse == 1.2.1 16:00:01 INFO - blessings == 1.6 16:00:01 INFO - blobuploader == 1.2.4 16:00:01 INFO - docopt == 0.6.1 16:00:01 INFO - functools32 == 3.2.3-2 16:00:01 INFO - jsonschema == 2.5.1 16:00:01 INFO - manifestparser == 1.1 16:00:01 INFO - mozInstall == 1.13 16:00:01 INFO - mozcrash == 1.0 16:00:01 INFO - mozdebug == 0.1 16:00:01 INFO - mozdevice == 0.51 16:00:01 INFO - mozfile == 1.2 16:00:01 INFO - mozhttpd == 0.7 16:00:01 INFO - mozinfo == 0.10 16:00:01 INFO - mozleak == 0.1 16:00:01 INFO - mozlog == 3.5 16:00:01 INFO - moznetwork == 0.27 16:00:01 INFO - mozprocess == 0.25 16:00:01 INFO - mozprofile == 0.28 16:00:01 INFO - mozrunner == 6.13 16:00:01 INFO - mozscreenshot == 0.1 16:00:01 INFO - mozsystemmonitor == 0.3 16:00:01 INFO - moztest == 0.8 16:00:01 INFO - mozversion == 1.4 16:00:01 INFO - psutil == 3.1.1 16:00:01 INFO - requests == 1.2.3 16:00:01 INFO - wsgiref == 0.1.2 16:00:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:00: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')]} 16:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00: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')]} 16:00:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:01 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 0x15441f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b19c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18aed60>, '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 0x18afa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18afef0>, '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': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', '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 16:00:01 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 16:00:01 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 16:00:01 INFO - Using env: {'DISPLAY': ':0', 16:00:01 INFO - 'HOME': '/home/cltbld', 16:00:01 INFO - 'LANG': 'en_US.UTF-8', 16:00:01 INFO - 'LANGUAGE': 'en_US:en', 16:00:01 INFO - 'LOGNAME': 'cltbld', 16:00:01 INFO - 'MAIL': '/var/mail/cltbld', 16:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:01 INFO - 'MOZ_NO_REMOTE': '1', 16:00:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:01 INFO - 'NO_EM_RESTART': '1', 16:00:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:01 INFO - 'PWD': '/builds/slave/test', 16:00:01 INFO - 'SHELL': '/bin/bash', 16:00:01 INFO - 'SHLVL': '1', 16:00:01 INFO - 'TERM': 'linux', 16:00:01 INFO - 'TMOUT': '86400', 16:00:01 INFO - 'USER': 'cltbld', 16:00:01 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', 16:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:01 INFO - '_': '/tools/buildbot/bin/python'} 16:00:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:02 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)) 16:00:02 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)) 16:00:02 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)) 16:00:02 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)) 16:00:02 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)) 16:00:02 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)) 16:00:02 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)) 16:00:02 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)) 16:00:02 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)) 16:00:02 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:00: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 16:00: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 16:00: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 16:00: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 16:00:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:00:03 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)) 16:00:03 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)) 16:00:03 INFO - Installing collected packages: simplejson 16:00:03 INFO - Running setup.py install for simplejson 16:00:03 INFO - building 'simplejson._speedups' extension 16:00:03 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 16:00:04 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 16:00:04 INFO - Successfully installed simplejson 16:00:04 INFO - Cleaning up... 16:00:04 INFO - Return code: 0 16:00:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:00:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15441f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b19c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18aed60>, '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 0x18afa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18afef0>, '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': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', '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 16:00:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:00:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:00:04 INFO - Using env: {'DISPLAY': ':0', 16:00:04 INFO - 'HOME': '/home/cltbld', 16:00:04 INFO - 'LANG': 'en_US.UTF-8', 16:00:04 INFO - 'LANGUAGE': 'en_US:en', 16:00:04 INFO - 'LOGNAME': 'cltbld', 16:00:04 INFO - 'MAIL': '/var/mail/cltbld', 16:00:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:04 INFO - 'MOZ_NO_REMOTE': '1', 16:00:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:04 INFO - 'NO_EM_RESTART': '1', 16:00:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:04 INFO - 'PWD': '/builds/slave/test', 16:00:04 INFO - 'SHELL': '/bin/bash', 16:00:04 INFO - 'SHLVL': '1', 16:00:04 INFO - 'TERM': 'linux', 16:00:04 INFO - 'TMOUT': '86400', 16:00:04 INFO - 'USER': 'cltbld', 16:00:04 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', 16:00:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:04 INFO - '_': '/tools/buildbot/bin/python'} 16:00:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:00:04 INFO - Cleaning up... 16:00:04 INFO - Return code: 0 16:00:04 INFO - Running post-action listener: _resource_record_post_action 16:00:04 INFO - Running post-action listener: _start_resource_monitoring 16:00:04 INFO - Starting resource monitoring. 16:00:04 INFO - [mozharness: 2017-09-22 23:00:04.674971Z] Finished create-virtualenv step (success) 16:00:04 INFO - [mozharness: 2017-09-22 23:00:04.675452Z] Running install step. 16:00:04 INFO - Running pre-action listener: _resource_record_pre_action 16:00:04 INFO - Running main action method: install 16:00:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:00:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:00:04 INFO - Reading from file tmpfile_stdout 16:00:04 INFO - Detecting whether we're running mozinstall >=1.0... 16:00:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:00:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:00:05 INFO - Reading from file tmpfile_stdout 16:00:05 INFO - Output received: 16:00:05 INFO - Usage: mozinstall [options] installer 16:00:05 INFO - Options: 16:00:05 INFO - -h, --help show this help message and exit 16:00:05 INFO - -d DEST, --destination=DEST 16:00:05 INFO - Directory to install application into. [default: 16:00:05 INFO - "/builds/slave/test"] 16:00:05 INFO - --app=APP Application being installed. [default: firefox] 16:00:05 INFO - mkdir: /builds/slave/test/build/application 16:00:05 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'] 16:00:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:00:22 INFO - Reading from file tmpfile_stdout 16:00:22 INFO - Output received: 16:00:22 INFO - /builds/slave/test/build/application/firefox/firefox 16:00:22 INFO - Running post-action listener: _resource_record_post_action 16:00:22 INFO - [mozharness: 2017-09-22 23:00:22.647298Z] Finished install step (success) 16:00:22 INFO - [mozharness: 2017-09-22 23:00:22.647551Z] Running setup-mitmproxy step. 16:00:22 INFO - Running pre-action listener: _resource_record_pre_action 16:00:22 INFO - Running main action method: setup_mitmproxy 16:00:22 INFO - Skipping: mitmproxy is not required 16:00:22 INFO - Running post-action listener: _resource_record_post_action 16:00:22 INFO - [mozharness: 2017-09-22 23:00:22.648683Z] Finished setup-mitmproxy step (success) 16:00:22 INFO - [mozharness: 2017-09-22 23:00:22.648902Z] Running run-tests step. 16:00:22 INFO - Running pre-action listener: _resource_record_pre_action 16:00:22 INFO - Running pre-action listener: _set_gcov_prefix 16:00:22 INFO - Running main action method: run_tests 16:00:22 WARNING - Try message not found. 16:00:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:00:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:00:22 INFO - Python 2.7.3 16:00:22 INFO - Return code: 0 16:00:22 INFO - grabbing minidump binary from tooltool 16:00:22 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')]} 16:00:22 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 0x18aed60>, '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 0x18afa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18afef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:00:22 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 16:00:22 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:00:22 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 16:00:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:00:22 INFO - Return code: 0 16:00:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:00:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:00:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:00:22 INFO - ENV: RUST_BACKTRACE is now full 16:00:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:00:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:00:22 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/dkFSWy0DRHCR9Q0gzCA1Rg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--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 16:00:22 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/dkFSWy0DRHCR9Q0gzCA1Rg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-050 --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 16:00:22 INFO - Using env: {'DISPLAY': ':0', 16:00:22 INFO - 'HOME': '/home/cltbld', 16:00:22 INFO - 'LANG': 'en_US.UTF-8', 16:00:22 INFO - 'LANGUAGE': 'en_US:en', 16:00:22 INFO - 'LOGNAME': 'cltbld', 16:00:22 INFO - 'MAIL': '/var/mail/cltbld', 16:00:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:00:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:22 INFO - 'MOZ_NO_REMOTE': '1', 16:00:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:00:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:22 INFO - 'NO_EM_RESTART': '1', 16:00:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:22 INFO - 'PWD': '/builds/slave/test', 16:00:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:00:22 INFO - 'RUST_BACKTRACE': 'full', 16:00:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:00:22 INFO - 'SHELL': '/bin/bash', 16:00:22 INFO - 'SHLVL': '1', 16:00:22 INFO - 'TERM': 'linux', 16:00:22 INFO - 'TMOUT': '86400', 16:00:22 INFO - 'USER': 'cltbld', 16:00:22 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', 16:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:22 INFO - '_': '/tools/buildbot/bin/python'} 16:00:22 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/dkFSWy0DRHCR9Q0gzCA1Rg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--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 16:00:23 INFO - mozversion application_buildid: 20170922212946 16:00:23 INFO - mozversion application_changeset: 61e58a7d800b8a84d3da7c3db977830e206428a3 16:00:23 INFO - mozversion application_display_name: Nightly 16:00:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:00:23 INFO - mozversion application_name: Firefox 16:00:23 INFO - mozversion application_remotingname: firefox 16:00:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:00:23 INFO - mozversion application_vendor: Mozilla 16:00:23 INFO - mozversion application_version: 58.0a1 16:00:23 INFO - mozversion platform_buildid: 20170922212946 16:00:23 INFO - mozversion platform_changeset: 61e58a7d800b8a84d3da7c3db977830e206428a3 16:00:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:00:23 INFO - mozversion platform_version: 58.0a1 16:00:23 INFO - using testdate: 1506121223 16:00:23 INFO - actual date: 1506121223 16:00:23 INFO - starting webserver on 'localhost:57295' 16:00:23 INFO - SUITE-START | Running 2 tests 16:00:23 INFO - TEST-START | dromaeo_css 16:00:23 INFO - Initialising browser for dromaeo_css test... 16:00:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57295/getInfo.html -profile /tmp/tmpjRBZAH/profile 16:00:23 INFO - TEST-INFO | started process 1095 (/builds/slave/test/build/application/firefox/firefox http://localhost:57295/getInfo.html) 16:00:28 INFO - TEST-INFO | 1095: exit 0 16:00:28 INFO - Browser initialized. 16:00:28 INFO - Running cycle 1/1 for dromaeo_css test... 16:00:28 INFO - Using env: {'DISPLAY': ':0', 16:00:28 INFO - 'HOME': '/home/cltbld', 16:00:28 INFO - 'JSGC_DISABLE_POISONING': '1', 16:00:28 INFO - 'LANG': 'en_US.UTF-8', 16:00:28 INFO - 'LANGUAGE': 'en_US:en', 16:00:28 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:00:28 INFO - 'LOGNAME': 'cltbld', 16:00:28 INFO - 'MAIL': '/var/mail/cltbld', 16:00:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:00:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:00:28 INFO - 'MOZ_CRASHREPORTER': '1', 16:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:28 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:00:28 INFO - 'MOZ_NO_REMOTE': '1', 16:00:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:00:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:28 INFO - 'NO_EM_RESTART': '1', 16:00:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:28 INFO - 'PWD': '/builds/slave/test', 16:00:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:00:28 INFO - 'RUST_BACKTRACE': 'full', 16:00:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:00:28 INFO - 'SHELL': '/bin/bash', 16:00:28 INFO - 'SHLVL': '1', 16:00:28 INFO - 'TERM': 'linux', 16:00:28 INFO - 'TMOUT': '86400', 16:00:28 INFO - 'USER': 'cltbld', 16:00:28 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', 16:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:28 INFO - '_': '/tools/buildbot/bin/python'} 16:00:28 INFO - TEST-INFO | started process 1420 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjRBZAH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:00:29 INFO - PID 1420 | 16:00:29 INFO - PID 1420 | (/builds/slave/test/build/application/firefox/firefox:1470): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:00:29 INFO - PID 1420 | 16:00:39 INFO - PID 1420 | RSS: Main: 144699392 16:00:39 INFO - PID 1420 | 16:03:37 INFO - PID 1420 | Cycle 1(1): loaded http://localhost:57295/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57295/tests/dromaeo/cssquery-ext.html) 16:03:38 INFO - PID 1420 | RSS: Main: 146374656 16:03:38 INFO - PID 1420 | 16:06:40 INFO - PID 1420 | Cycle 1(1): loaded http://localhost:57295/tests/dromaeo/cssquery-ext.html (next: http://localhost:57295/tests/dromaeo/cssquery-jquery.html) 16:06:40 INFO - PID 1420 | RSS: Main: 146386944 16:06:40 INFO - PID 1420 | 16:09:42 INFO - PID 1420 | Cycle 1(1): loaded http://localhost:57295/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57295/tests/dromaeo/cssquery-mootools.html) 16:09:43 INFO - PID 1420 | RSS: Main: 147869696 16:09:43 INFO - PID 1420 | 16:12:44 INFO - PID 1420 | Cycle 1(1): loaded http://localhost:57295/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57295/tests/dromaeo/cssquery-prototype.html) 16:12:45 INFO - PID 1420 | RSS: Main: 147238912 16:12:45 INFO - PID 1420 | 16:15:48 INFO - PID 1420 | Cycle 1(1): loaded http://localhost:57295/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57295/tests/dromaeo/cssquery-yui.html) 16:15:48 INFO - PID 1420 | RSS: Main: 148393984 16:15:48 INFO - PID 1420 | 16:18:50 INFO - PID 1420 | Cycle 1(1): loaded http://localhost:57295/tests/dromaeo/cssquery-yui.html (next: http://localhost:57295/tests/dromaeo/cssquery-dojo.html) 16:18:50 INFO - PID 1420 | RSS: Main: 150536192 16:18:50 INFO - PID 1420 | 16:18:50 INFO - PID 1420 | __start_tp_report 16:18:50 INFO - PID 1420 | _x_x_mozilla_page_load 16:18:50 INFO - PID 1420 | _x_x_mozilla_page_load_details 16:18:50 INFO - PID 1420 | |i|pagename|runs| 16:18:50 INFO - PID 1420 | |0;dojo.html;3825.7321987460887;3834.7507412018217;3870.3226935286316;3880.980595097025;3883.203943191646;6062.969685151579;6262.874742505147;6286.308506064329;6295.905561416583;6403.839904002396;6270.905936410946;6345.428911397973;6347.5239357048295;6371.776987805428;6381.968090159543;5563.443655634444;5593.2449119368775;5660.688662123582;5666.008448521513;5691.117876729093;5631.042722737145;5669.177969194464;5688.402717714643;5735.168400581913;5750.166225897243;5670.518005969477;5697.77208911643;5797.246357973458;5800.738966746486;5804.071348584227;5493.752781124819;5637;5689.516391106719;5713.14302854571;5720.2849643794525;5641.182028605829;5656.490915817555;5706.201131841545;5709.257796486471;5714;6129.356417576179;6236.095766113888;6256.124142620037;6285.717142728588;6367.140436041122;6740;6761.966190169067;6768.49236307274;6776.864462710768;6842.897356539655;6825.48808839339;6879.5184337095925;6899.206591242021;6903.965480172567;6912.481563882729;6308.873822523524;6327.873442531123;6348.9524228502705;6355.650439225795;6423.871522569522;6931.618760968095;6960.443164546926;7022.40309573692;7050.541714788625;7062.505624606229;6552.082708420825;6686.3313668632745;6737.663116844236;6764.864702705919;6770.4922130839395;6839.076724642109;6851.143607049119;6851.588904665637;6861.55399899005;6868.072810170663;6599.043138744855;6618;6691.799246022627;6696.966515167393;6704.195496540349;6669.533132680665;6733.293004234485;6789.592624442549;6809.182898052265;6823.249442561314;4956.306117143603;4995.750212489434;4996.6502344836235;4997.525235102659;5009.44896061433;4525.343825145335;4533.274676051816;4559.726416415026;4571.9085618287445;4578;6223.066540019034;6223.875522489616;6242.063690446378;6302.306746257908;6314.905276420857;6601.636909969998;6734.528582999241;6741.1236539249585;6901.689423975933;6907.34380233877;6218.720157592919;6220.626762394271;6249.0938813871735;6257.530685198628;6271.560990730696;5460.863478413072;5475.06923822951;5529.917051244234;5548.361938377057;5549.639273447213;6899.310068993061;6930.653467326613;6945.652717364112;6991.161060672752;7033.788986330418;6775.796726098225;6777.525573209927;6858.897116543356;6893.586384816927;6894.655267236719;6993.19578248508;7008.123984501937;7083.574985500681;7089.574625522485;7091.751788687371;6685.064091027163;6686.732530698718;6718.798436046926;6739.93260067393;6803.965980170067;6872.347127022925;6921.1002569667635;6933.2720064392515;6939.410150137295;6940.652967351713;6547.770828020996;6607.174103237096;6617.305182955913;6695.832604184934;6701.530892837454;6582.868342633216;6583.275839657538;6663.8667226654225;6668.366505182;6697;6494.2856285807575;6566.4418524425955;6582.670866456754;6586.2755096940255;6596.241432235162;6666.899996499959;6674.365935236145;6693.163354580677;6760.1549806274215;6781.593104413751;6364.331745166691;6436.227652681709;6492.513061520311;6493.090967264492;6530.379613936669 16:18:50 INFO - PID 1420 | |1;ext.html;963.1091240602443;987.5555999800092;988.2983082011772;993.0913214408816;993.3841018568486;40991.15539800709;44649.55350446499;45355.0928981421;45370.77314613426;45433;43387.13225735483;43530;43670.56329436705;43735.343969840476;43888;7791.610419479031;7891.447598668095;7903.762887113396;7905.644246008944;7914.920850791505;7733.342665873408;7833.96083019587;7879.8424031519335;7880.724174653888;7895.447318687666;7841.3726901848;7870.803229919269;7898.684052637887;7902.407319451036;7902.486338388015;7764.3012128908385;8370.288525475342;8673.566321683922;8675.95662021692;8682.26200772935;8420.621072051772;8441.240288374076;8493.36299777513;8504.872426913602;8505.617247223829;41514;41890;42205;42746.572534274885;42850;3707.870224542128;3742.644448777362;3744.9438258426144;3756.9248615027545;3758.342290099222;34074.829625851715;34256.14359641004;34348.82825585856;34516;34567.308653827036;5306.071437498461;5307.628466007381;5326.414094449608;5330.626856120073;5344.35867695879;211106;213576;213653;214177;215381.99999999843;46656.066878662234;47550.5244947553;51414.22878656823;51758.48241517612;52201.2169817453;54461.72769136208;54515.72742136264;54563.18155227675;54589.45410545844;54635.45364546383;16221.918890405708;16263.837361626469;16293.429729959395;16339.101349425895;16354.918225409036;13045.34773261333;13050.804237936438;13079.41142648583;13085;13325.800112998313;14468.131912085099;14504.129752214902;14594.270286485633;14604.561863144123;14757.77863332051;5616.326040875121;5742.397048309951;5761.711914404263;5778.162166485836;5923.259592550931;1078.455380033094;1093.2347356850078;1107.5680484611007;1124.4883577972166;1127.7969965406305;25875.48249035009;25918.22245332643;26262.737372626412;26320;26488.735112649014;1208.5528354508713;1223.4005337384617;1224.6693392783898;1234.7592219517287;1241.4351470081092;13690.93154534221;13859.792103118461;13884;14121;14210.928945355206;272066;272255;273799;274290;274348;58310.125348119815;58523;58771.41228587745;64505;65415;1510.2750679673818;1533.4173014254511;1538.4000239906445;1540.4454396417282;1546;31174.688253118085;31321.216969575944;31740.682593173773;32185.195370114998;32482.35035299328;44036.11927761491;44275;44339.77830110829;44369.778151109036;44431;256944;257029;257291;257443;258066;38471.80764096162;38553;39330;39349.40975885421;39377.40933886051;47064.7646761764;48039.519604804904;48677.2698409517;48754.26868596903;48821;35257.76597819064;35322.470162947066;35417.16662667032;35489.290214195054;36292;35630;35642.148628110204;36092;36126.819365903;36605.26789464142;41152;41252.793736031126;41884.58115418807;41939;41942.161156776085;34271;34603.82698086594;34938.30123397569;35087.12282192965;35229.471557927165;25685.16602436275;25985.350366240997;26334.20997370082;26719.332016699736;26821 16:18:50 INFO - PID 1420 | |2;jquery.html;604.7671646416128;610.2219669920852;619.2630769384432;624.6470744029623;626.2422468812737;22621.773782262193;23377.064917403306;23538.529229415424;23561.64657530134;23586.056557737713;23232.535349293004;24899;24961.87519062407;24974.125905593308;25017;6773.796786096413;6925.65371731414;6983.092198014251;6988.266232045638;6997.475189360793;6682.799516014527;6857.211420686635;6919.688614012382;6963.09479767632;6967.059446974669;6887.242403335629;6891.310868913119;6915.723371065176;6922.619255940921;6925;6511;6797.966010169967;6801.081853949728;6803.047573339736;6813.569150478405;6363.713632886531;6366.713497892606;6375.744970201186;6376;6379.266384365811;30669.386612267852;30837.38325233483;30992.845035774902;31016.53475197874;31064;2240.19353032908;2288.645259984705;2297.1959814065112;2299.54009198161;2300.5628930503317;20234.392968210977;20974.16103355864;21013.159473621035;21017.789822101735;21029.89485052565;2524.507720994407;2540.8602526861014;2586.172424824057;2653.2968763277745;2665.5335316319565;138921.3053934734;146125.26937365351;146600;152427;159348.4065159345;16191.433299834685;16863.15684215784;17179.226934787963;17242.741358879626;17291.82708172902;26617.201483955512;26708.59937100945;26756.197314080608;26822;26923.326916826845;10652.360858348524;10713.946430267799;10839.729006774736;10954.56181752721;11003.669889903316;9798.412095274307;9833.950830245803;9855.80288394228;9897.80204395908;9959.05388988045;10132.645357412455;10169.96600679867;10200.3369780964;10329.276950613536;10523;2979.4041191761735;2996.5654980027775;3075.7231849133686;3122.3919681363664;3141.293209027996;1022.4172221833484;1037.5331101004579;1038.2991480750613;1053.146950969717;1054.5323941975814;21855.344339669835;21991;22161.889190554266;22216.22243221512;22468.21361252381;1085.719988025141;1104.6685994201707;1105.6075093341908;1112.3548341961643;1123.1407972900738;11065.336079835235;11911.880881191251;11914.225575337749;12012.459439325252;12068.456919438646;595917;599682;609945;613936;615204;124828.37585812013;127245.36377318054;130600;130835;131381;1213.466074927047;1215.0644004116957;1242.465739731924;1245.6574442028334;1251.249250449741;19015.64654397245;19107.713384299524;19370.322038728576;19568.21727130899;19744.308949187285;59028;60000.39999600123;61143.38856611555;61170.38829611647;61993.38006619876;75259.24740752522;75396.62301688457;76198.61900690461;76471.61764191143;76733;15797.605059873595;15875.12686802237;15931.203439828194;16082.517524474284;16106.436274730328;23783.64324535168;23998.52002959896;24123.879380602986;24294.757052429955;24470.877645611657;19522.121504532046;19644.60710785805;19831.60336793227;19846.801531984496;19889.900550497154;17104.657906841545;17198.31206751716;17490.650186996445;17583;17723.657703862635;19292.45660347197;19337;19346;19422.708659370404;19553.21787128499;15514.301856416263;15570.377184912477;15746.9212653936;15799.815013874235;15837.366505339658;19689.310874119335;19774.90112549428;20148.69033512817;20305.390838274874;20349.49126271855 16:18:50 INFO - PID 1420 | |3;mootools.html;1427.157976793692;1512.5991612222763;1514.7349213887574;1528.4039224702376;1530.395493779957;16581.170941452925;17315.91342043288;17346.653066938652;17356.652866942684;17373.131343432808;17025.744613830804;17089.57276068099;17199.312027518885;17227.05251211183;17232.56918577036;2861.1273561563717;2913;2920.2261400728826;2946.0867131189293;2946.4107178564263;2848.914532564016;2873.6264285642837;2896.666883308415;2900.361920377514;2902.7097290271017;2711.3628297350106;2822.2379957411486;2956.231379841235;2962.1261727790247;2967.5845381646586;2827.4486475137355;2856.128880691388;2865.126136528358;2879.093085678015;2883.9134825955257;2762.350847550824;2794.203651959182;2799.0902956539207;2810.6065150878894;2847.900323488668;16705.91647041769;16971.66056678872;16979.915100424543;16985.49043528696;17004.574885627833;8105.594720263964;8289.626966786509;8381.664733410656;8384.916150838535;8392.328613710888;5336.573074154058;5487.506124448779;5566.972165139189;5619.522340601054;5627.971860140714;2136.102836808544;2162.4701948022835;2163.5348400094053;2185.5628874225217;2222.7777222277805;31092.689073109206;31241.218969525715;31383.37233255345;31410.528842067386;31416.99999999977;7420.480566360303;7524.924750752422;7553.8866916996285;7692.692292308357;7710.383169346439;20080.59838803216;20129.79870201279;20163.899180504002;20164.495887602632;20874.47813804637;8440.15598440151;8587.871181932276;8681.2186903179;8689.304855611535;8694.782630434163;7597.886031709529;7748.690052397954;7780;7822.022247219098;7880.093789214199;5532.225488431623;5732.05421105513;5734.139879018181;5771.105478650816;5823.56323275756;1679.5381270150563;1725.887817291872;1734.4276388791668;1742.8867123636924;1770.2211027148014;1003;1020.9387436753818;1025.0927928782933;1025.374521541859;1044.7074819050679;8700.738977830675;8828.51443170619;8839.071897450804;8933.062028487046;8990.775230619287;815.3069890592949;817.2603793566832;822.8436597046542;828.5401602110766;832.0888626953476;5205.7397130143345;5466.070767969455;5525.972370138204;5732.140178973104;6130.478909292671;23924.282271531883;25536.23391298227;25762.227133186036;25819.225423237334;25839;23790.64314035291;25093.74906250914;25169.867355969025;25483.10809121709;25502.872485637825;12587.874121258854;12694.746105078031;12797.23216606988;12822.487100515875;12837;9315;9414.2468602513;9429.05709429079;9430.764230894009;9442.48066356357;20904.1638334471;21985.890070549543;22059.11763529441;22228.88885555562;22259.443513912283;21637.675434868175;22121.225757098426;22614;22621;22643.773562264825;12554.43505042257;12613.36933153357;12715.364231788559;12730.427130778951;12747.93626031901;14788.260586970824;15119.697606048037;15146.545603631908;15154.469593564172;15165.469208577646;11739.647810565697;11898.345590992578;11943.641690749291;12023.819642705192;12221.633350999484;12604.936975315064;12631.621051368473;12716.491340346653;12734;13149.53976610814;10147;10305.227107967083;10406.167506599302;10420.895791041992;10422.947885260524;7957.56233407145;8036.437449378721;8143.51138931666;8149.9185008151535;8160.5103693778565;8111.75664730059;8134.308583770446;8209.79475513117;8320;8376.958115209385 16:18:50 INFO - PID 1420 | |4;prototype.html;568.1137425616039;569.7066011004332;576.5704550110167;593.7713980117653;599.5143933413935;6340.270868850078;6367.49060075194;6383.138276332694;6392.808215753529;6403.367141378948;6125.663088530129;6258.593191442565;6288.308286088538;6295.181626388575;6322.968385158079;4930.211166213407;5043.193089105744;5075.923861142086;5146.794128234867;5162.432132465435;5012.398512178544;5038;5054.620903432239;5057.848264552069;5068.5438310552045;5021.748912554358;5023.120953833083;5031.163625276766;5034.697918124924;5034.748262586856;4965.205567109263;5001.17480615698;5010.674306170124;5022.17134172861;5043.52086551777;4606.723596584216;4620.283856002325;4620.930686039712;4623.144718227139;4627.398438203012;6123.846903827395;6133.6626485543275;6221.751129954796;6268.717907694165;6289.811305660837;5869;5898.321693005317;5907.616004959664;5923.229980102602;5942.286925568917;6662.56693314933;6692.866142677168;6714.496412769062;6742.898856517156;6758.1752926710005;4818.060478206751;5114.079465696173;5127.66670166438;5145.948540514584;5153.845384638467;6788.253292137861;6807.693653785592;6845.828854278633;6863.725450982005;6905.723771049152;5171.637985341065;5216.739163041832;5219.660722053054;5231.503007214308;5232.717984093922;5251.947480525146;5288.233206185082;5294.92057619136;5298.834256463572;5306.46935306474;5782.45066718661;5792.971035144881;5821.446962538552;5855.648661080264;5902.3507414184405;6680.5323627345615;6695.192298079589;6695.765648202289;6732.697028633724;6774.153230846145;1808.9366872159412;1813.6372725454962;1830.2130084063715;1837.1549087419937;1849.4913898677971;1241.5654520917606;1296.6499045257729;1303.4134639412302;1316.493150137204;1317.1833463252653;5920.407959204045;5925.970370148208;5963.880722385528;6004.159417681528;6067.45392914631;6543.509236807258;6560.376764207393;6602.207735071822;6609.239937407259;6709.7316107355155;6076.969615151896;6140.618360868769;6166;6189.319174890758;6206;3497.370473314777;3515.1739341254784;3544.911377215539;3570.0539357070397;3576.818140934028;6957.339052789976;7126.5034342788595;7129.215786263507;7135.572885422936;7145.535540189871;6930.410915072174;6941.965290173618;6958.165020197609;6968.198657154492;7001.50989430735;6958.269381715049;7069.505134640629;7125.539264450776;7134.714611415485;7159.534630249122;6759.222689390783;6771.255161932085;6788.93211067883;6810;6831.760888368884;6614.503912206507;6719.596824190564;6728.697208625722;6777.457803375817;6845.418139458003;7003.4397248219;7034.824129396806;7069.929300706927;7127.679254433667;7160.606166660884;5131.307273518028;5233.790648374174;5266.42069372361;5278.9472105278455;5287.9735601321745;6594.703238354382;6756.69594868242;6822.897656535255;6885.139357580302;6911;6497.610143391412;6626.734930602915;6638.668066596554;6673.366030227121;6676.599404035774;6613.834654133492;6616.503762217949;6645.634490103091;6662.533622646368;6686.264510903699;6446.742130314735;6474.935250647622;6481.157449531531;6511.218653761579;6542.182227221598;6370.203724534434;6575.769848055295;6595.88931541902;6631.469482441489;6691.130153080068;6352.650604216812;6372.745090196526;6398.424141827258;6458.192725909262;6523.24982626985 16:18:50 INFO - PID 1420 | |5;yui.html;954.3415043619906;988.560090759612;997.3666721631766;997.9870431512012;1012.8683271174743;19615.509612259702;20111.698324525143;20140.899295503503;20158.496037599067;20158.89920550399;18275;18329;18552.25790968363;18621.534461638465;18746;1062.1768129699506;1087.809633314171;1088.5264909764278;1089.3463921647028;1090.0080926357016;1067.4235912607182;1083.6694808083532;1097.8408130821067;1098.0392156862745;1098.2092893116942;1130.5760339872547;1131.4003578103577;1131.835883796849;1137.385811661706;1142.4173671427548;1129.0967226219075;1131.5021390588156;1141.2866958151155;1144.1247445704043;1150.8388825564427;797.3979645367768;812.8536863364592;818.2880893622527;818.7298191596759;818.8894499242585;21077.26229581957;21419;21441.356759297247;21448.678269825967;21458;22231.888840555694;22572.322830315115;23243.302700919;23259;23412.180573679838;18632.627347452973;18728.625427491374;18862.056897155224;18959.24163033477;18962.71555926662;3910.706696997708;3954.0510277533185;3955.367141257414;3956.188981258835;3957.3668213086057;156706;157231;157548;158603;161074;17375.131243437776;17423.128843558025;18078.5480362992;18162.09189540544;18382.540436489195;33546.3290734184;34238.82880585581;34305.313893721985;34378;34832;9096.317776166681;9187.402818816756;9223.53882305882;9239.861402078974;9256.629734810667;4623.745693986863;4719.598834099072;4764.642651801059;4765.904681906342;4767.594754445841;5655;5917.082852157922;5941.881162376728;5952.255968003999;5957.195778569924;1040.0743338428804;1073.88187299397;1081.8214994525815;1082.8700555933337;1083.6261489786011;727.1710250314645;745.6048294403975;754.3738696881575;765.4871236271712;774.2915232562256;12453.626391208278;12462.43919023646;12668.303243321521;13101.344932753324;13412.262325572186;725.6190499987443;736.4550232827669;738.7045181927272;739.4638886807022;747.8504299140194;6204.348543402968;6262.2172228471445;6294.842628934313;6352.587081839665;6447.6453795041725;148685;152415.2379238119;152564;152952;155179;133547.33226333806;133929;135431.3228433871;136415.31792340975;139822;109051;110238;111024.44487777671;111175;112315;11517;11678.591249306573;11831.290122592673;11935.701607459536;12047.638570842539;56394.7180264096;56503.434965649816;57092.714536427055;57370.426295736506;59043.40956590379;70680.93978590223;72300;72314;73098;73385.6330718343;15627.296771645077;16525.83474165243;16613.41853035138;16638.83361166373;16653.666926661157;16965.23656435488;17376.304947801946;17441.56396090108;17447.127643618023;17576;16648.417305394254;16752.916235418747;16848.07335596554;16853.915730421268;16961.83038169652;16059.598510037342;16158.757618635966;16374.263158157673;16375.59061023484;16608.418705345255;15342.15618141013;15344.156071416179;15519.068855868683;15534.1456219909;15587.532374028797;12534.811977820522;12610.43253053633;12752.298623575793;12952.28762418076;13182.538611148564;12046.578369757017;12186.573469928864;12188.49545610901;12202.511899523919;12250.448729807002 16:18:50 INFO - PID 1420 | __end_tp_report 16:18:50 INFO - PID 1420 | __start_cc_report 16:18:50 INFO - PID 1420 | _x_x_mozilla_cycle_collect,284 16:18:50 INFO - PID 1420 | __end_cc_report 16:18:50 INFO - PID 1420 | __startTimestamp1506122330538__endTimestamp 16:18:50 INFO - PID 1420 | 16:18:50 INFO - PID 1420 | ------- Summary: start ------- 16:18:50 INFO - PID 1420 | Number of tests: 6 16:18:50 INFO - PID 1420 | 16:18:50 INFO - PID 1420 | [#0] dojo.html Cycles:165 Average:6272.79 Median:6552.08 stddev:736.66 (11.2%) stddev-sans-first:713.46 16:18:50 INFO - PID 1420 | Values: 3825.7 3834.8 3870.3 3881.0 3883.2 6063.0 6262.9 6286.3 6295.9 6403.8 6270.9 6345.4 6347.5 6371.8 6382.0 5563.4 5593.2 5660.7 5666.0 5691.1 5631.0 5669.2 5688.4 5735.2 5750.2 5670.5 5697.8 5797.2 5800.7 5804.1 5493.8 5637.0 5689.5 5713.1 5720.3 5641.2 5656.5 5706.2 5709.3 5714.0 6129.4 6236.1 6256.1 6285.7 6367.1 6740.0 6762.0 6768.5 6776.9 6842.9 6825.5 6879.5 6899.2 6904.0 6912.5 6308.9 6327.9 6349.0 6355.7 6423.9 6931.6 6960.4 7022.4 7050.5 7062.5 6552.1 6686.3 6737.7 6764.9 6770.5 6839.1 6851.1 6851.6 6861.6 6868.1 6599.0 6618.0 6691.8 6697.0 6704.2 6669.5 6733.3 6789.6 6809.2 6823.2 4956.3 4995.8 4996.7 4997.5 5009.4 4525.3 4533.3 4559.7 4571.9 4578.0 6223.1 6223.9 6242.1 6302.3 6314.9 6601.6 6734.5 6741.1 6901.7 6907.3 6218.7 6220.6 6249.1 6257.5 6271.6 5460.9 5475.1 5529.9 5548.4 5549.6 6899.3 6930.7 6945.7 6991.2 7033.8 6775.8 6777.5 6858.9 6893.6 6894.7 6993.2 7008.1 7083.6 7089.6 7091.8 6685.1 6686.7 6718.8 6739.9 6804.0 6872.3 6921.1 6933.3 6939.4 6940.7 6547.8 6607.2 6617.3 6695.8 6701.5 6582.9 6583.3 6663.9 6668.4 6697.0 6494.3 6566.4 6582.7 6586.3 6596.2 6666.9 6674.4 6693.2 6760.2 6781.6 6364.3 6436.2 6492.5 6493.1 6530.4 16:18:50 INFO - PID 1420 | 16:18:50 INFO - PID 1420 | [#1] ext.html Cycles:180 Average:43235.52 Median:38512.40 stddev:64794.38 (168.2%) stddev-sans-first:64898.41 16:18:50 INFO - PID 1420 | Values: 963.1 987.6 988.3 993.1 993.4 40991.2 44649.6 45355.1 45370.8 45433.0 43387.1 43530.0 43670.6 43735.3 43888.0 7791.6 7891.4 7903.8 7905.6 7914.9 7733.3 7834.0 7879.8 7880.7 7895.4 7841.4 7870.8 7898.7 7902.4 7902.5 7764.3 8370.3 8673.6 8676.0 8682.3 8420.6 8441.2 8493.4 8504.9 8505.6 41514.0 41890.0 42205.0 42746.6 42850.0 3707.9 3742.6 3744.9 3756.9 3758.3 34074.8 34256.1 34348.8 34516.0 34567.3 5306.1 5307.6 5326.4 5330.6 5344.4 211106.0 213576.0 213653.0 214177.0 215382.0 46656.1 47550.5 51414.2 51758.5 52201.2 54461.7 54515.7 54563.2 54589.5 54635.5 16221.9 16263.8 16293.4 16339.1 16354.9 13045.3 13050.8 13079.4 13085.0 13325.8 14468.1 14504.1 14594.3 14604.6 14757.8 5616.3 5742.4 5761.7 5778.2 5923.3 1078.5 1093.2 1107.6 1124.5 1127.8 25875.5 25918.2 26262.7 26320.0 26488.7 1208.6 1223.4 1224.7 1234.8 1241.4 13690.9 13859.8 13884.0 14121.0 14210.9 272066.0 272255.0 273799.0 274290.0 274348.0 58310.1 58523.0 58771.4 64505.0 65415.0 1510.3 1533.4 1538.4 1540.4 1546.0 31174.7 31321.2 31740.7 32185.2 32482.4 44036.1 44275.0 44339.8 44369.8 44431.0 256944.0 257029.0 257291.0 257443.0 258066.0 38471.8 38553.0 39330.0 39349.4 39377.4 47064.8 48039.5 48677.3 48754.3 48821.0 35257.8 35322.5 35417.2 35489.3 36292.0 35630.0 35642.1 36092.0 36126.8 36605.3 41152.0 41252.8 41884.6 41939.0 41942.2 34271.0 34603.8 34938.3 35087.1 35229.5 25685.2 25985.4 26334.2 26719.3 26821.0 16:18:50 INFO - PID 1420 | 16:18:50 INFO - PID 1420 | [#2] jquery.html Cycles:180 Average:39643.44 Median:22544.99 stddev:101358.18 (449.6%) stddev-sans-first:101600.13 16:18:50 INFO - PID 1420 | Values: 604.8 610.2 619.3 624.6 626.2 22621.8 23377.1 23538.5 23561.6 23586.1 23232.5 24899.0 24961.9 24974.1 25017.0 6773.8 6925.7 6983.1 6988.3 6997.5 6682.8 6857.2 6919.7 6963.1 6967.1 6887.2 6891.3 6915.7 6922.6 6925.0 6511.0 6798.0 6801.1 6803.0 6813.6 6363.7 6366.7 6375.7 6376.0 6379.3 30669.4 30837.4 30992.8 31016.5 31064.0 2240.2 2288.6 2297.2 2299.5 2300.6 20234.4 20974.2 21013.2 21017.8 21029.9 2524.5 2540.9 2586.2 2653.3 2665.5 138921.3 146125.3 146600.0 152427.0 159348.4 16191.4 16863.2 17179.2 17242.7 17291.8 26617.2 26708.6 26756.2 26822.0 26923.3 10652.4 10713.9 10839.7 10954.6 11003.7 9798.4 9834.0 9855.8 9897.8 9959.1 10132.6 10170.0 10200.3 10329.3 10523.0 2979.4 2996.6 3075.7 3122.4 3141.3 1022.4 1037.5 1038.3 1053.1 1054.5 21855.3 21991.0 22161.9 22216.2 22468.2 1085.7 1104.7 1105.6 1112.4 1123.1 11065.3 11911.9 11914.2 12012.5 12068.5 595917.0 599682.0 609945.0 613936.0 615204.0 124828.4 127245.4 130600.0 130835.0 131381.0 1213.5 1215.1 1242.5 1245.7 1251.2 19015.6 19107.7 19370.3 19568.2 19744.3 59028.0 60000.4 61143.4 61170.4 61993.4 75259.2 75396.6 76198.6 76471.6 76733.0 15797.6 15875.1 15931.2 16082.5 16106.4 23783.6 23998.5 24123.9 24294.8 24470.9 19522.1 19644.6 19831.6 19846.8 19889.9 17104.7 17198.3 17490.7 17583.0 17723.7 19292.5 19337.0 19346.0 19422.7 19553.2 15514.3 15570.4 15746.9 15799.8 15837.4 19689.3 19774.9 20148.7 20305.4 20349.5 16:18:50 INFO - PID 1420 | 16:18:50 INFO - PID 1420 | [#3] mootools.html Cycles:180 Average:10510.07 Median:2736.86 stddev:7861.85 (287.3%) stddev-sans-first:7854.29 16:18:50 INFO - PID 1420 | Values: 1427.2 1512.6 1514.7 1528.4 1530.4 16581.2 17315.9 17346.7 17356.7 17373.1 17025.7 17089.6 17199.3 17227.1 17232.6 2861.1 2913.0 2920.2 2946.1 2946.4 2848.9 2873.6 2896.7 2900.4 2902.7 2711.4 2822.2 2956.2 2962.1 2967.6 2827.4 2856.1 2865.1 2879.1 2883.9 2762.4 2794.2 2799.1 2810.6 2847.9 16705.9 16971.7 16979.9 16985.5 17004.6 8105.6 8289.6 8381.7 8384.9 8392.3 5336.6 5487.5 5567.0 5619.5 5628.0 2136.1 2162.5 2163.5 2185.6 2222.8 31092.7 31241.2 31383.4 31410.5 31417.0 7420.5 7524.9 7553.9 7692.7 7710.4 20080.6 20129.8 20163.9 20164.5 20874.5 8440.2 8587.9 8681.2 8689.3 8694.8 7597.9 7748.7 7780.0 7822.0 7880.1 5532.2 5732.1 5734.1 5771.1 5823.6 1679.5 1725.9 1734.4 1742.9 1770.2 1003.0 1020.9 1025.1 1025.4 1044.7 8700.7 8828.5 8839.1 8933.1 8990.8 815.3 817.3 822.8 828.5 832.1 5205.7 5466.1 5526.0 5732.1 6130.5 23924.3 25536.2 25762.2 25819.2 25839.0 23790.6 25093.7 25169.9 25483.1 25502.9 12587.9 12694.7 12797.2 12822.5 12837.0 9315.0 9414.2 9429.1 9430.8 9442.5 20904.2 21985.9 22059.1 22228.9 22259.4 21637.7 22121.2 22614.0 22621.0 22643.8 12554.4 12613.4 12715.4 12730.4 12747.9 14788.3 15119.7 15146.5 15154.5 15165.5 11739.6 11898.3 11943.6 12023.8 12221.6 12604.9 12631.6 12716.5 12734.0 13149.5 10147.0 10305.2 10406.2 10420.9 10422.9 7957.6 8036.4 8143.5 8149.9 8160.5 8111.8 8134.3 8209.8 8320.0 8377.0 16:18:50 INFO - PID 1420 | 16:18:50 INFO - PID 1420 | [#4] prototype.html Cycles:180 Average:5659.17 Median:6278.51 stddev:1579.27 (25.2%) stddev-sans-first:1536.78 16:18:50 INFO - PID 1420 | Values: 568.1 569.7 576.6 593.8 599.5 6340.3 6367.5 6383.1 6392.8 6403.4 6125.7 6258.6 6288.3 6295.2 6323.0 4930.2 5043.2 5075.9 5146.8 5162.4 5012.4 5038.0 5054.6 5057.8 5068.5 5021.7 5023.1 5031.2 5034.7 5034.7 4965.2 5001.2 5010.7 5022.2 5043.5 4606.7 4620.3 4620.9 4623.1 4627.4 6123.8 6133.7 6221.8 6268.7 6289.8 5869.0 5898.3 5907.6 5923.2 5942.3 6662.6 6692.9 6714.5 6742.9 6758.2 4818.1 5114.1 5127.7 5145.9 5153.8 6788.3 6807.7 6845.8 6863.7 6905.7 5171.6 5216.7 5219.7 5231.5 5232.7 5251.9 5288.2 5294.9 5298.8 5306.5 5782.5 5793.0 5821.4 5855.6 5902.4 6680.5 6695.2 6695.8 6732.7 6774.2 1808.9 1813.6 1830.2 1837.2 1849.5 1241.6 1296.6 1303.4 1316.5 1317.2 5920.4 5926.0 5963.9 6004.2 6067.5 6543.5 6560.4 6602.2 6609.2 6709.7 6077.0 6140.6 6166.0 6189.3 6206.0 3497.4 3515.2 3544.9 3570.1 3576.8 6957.3 7126.5 7129.2 7135.6 7145.5 6930.4 6942.0 6958.2 6968.2 7001.5 6958.3 7069.5 7125.5 7134.7 7159.5 6759.2 6771.3 6788.9 6810.0 6831.8 6614.5 6719.6 6728.7 6777.5 6845.4 7003.4 7034.8 7069.9 7127.7 7160.6 5131.3 5233.8 5266.4 5278.9 5288.0 6594.7 6756.7 6822.9 6885.1 6911.0 6497.6 6626.7 6638.7 6673.4 6676.6 6613.8 6616.5 6645.6 6662.5 6686.3 6446.7 6474.9 6481.2 6511.2 6542.2 6370.2 6575.8 6595.9 6631.5 6691.1 6352.7 6372.7 6398.4 6458.2 6523.2 16:18:50 INFO - PID 1420 | 16:18:50 INFO - PID 1420 | [#5] yui.html Cycles:180 Average:28097.28 Median:17444.35 stddev:42556.53 (244.0%) stddev-sans-first:42627.11 16:18:50 INFO - PID 1420 | Values: 954.3 988.6 997.4 998.0 1012.9 19615.5 20111.7 20140.9 20158.5 20158.9 18275.0 18329.0 18552.3 18621.5 18746.0 1062.2 1087.8 1088.5 1089.3 1090.0 1067.4 1083.7 1097.8 1098.0 1098.2 1130.6 1131.4 1131.8 1137.4 1142.4 1129.1 1131.5 1141.3 1144.1 1150.8 797.4 812.9 818.3 818.7 818.9 21077.3 21419.0 21441.4 21448.7 21458.0 22231.9 22572.3 23243.3 23259.0 23412.2 18632.6 18728.6 18862.1 18959.2 18962.7 3910.7 3954.1 3955.4 3956.2 3957.4 156706.0 157231.0 157548.0 158603.0 161074.0 17375.1 17423.1 18078.5 18162.1 18382.5 33546.3 34238.8 34305.3 34378.0 34832.0 9096.3 9187.4 9223.5 9239.9 9256.6 4623.7 4719.6 4764.6 4765.9 4767.6 5655.0 5917.1 5941.9 5952.3 5957.2 1040.1 1073.9 1081.8 1082.9 1083.6 727.2 745.6 754.4 765.5 774.3 12453.6 12462.4 12668.3 13101.3 13412.3 725.6 736.5 738.7 739.5 747.9 6204.3 6262.2 6294.8 6352.6 6447.6 148685.0 152415.2 152564.0 152952.0 155179.0 133547.3 133929.0 135431.3 136415.3 139822.0 109051.0 110238.0 111024.4 111175.0 112315.0 11517.0 11678.6 11831.3 11935.7 12047.6 56394.7 56503.4 57092.7 57370.4 59043.4 70680.9 72300.0 72314.0 73098.0 73385.6 15627.3 16525.8 16613.4 16638.8 16653.7 16965.2 17376.3 17441.6 17447.1 17576.0 16648.4 16752.9 16848.1 16853.9 16961.8 16059.6 16158.8 16374.3 16375.6 16608.4 15342.2 15344.2 15519.1 15534.1 15587.5 12534.8 12610.4 12752.3 12952.3 13182.5 12046.6 12186.6 12188.5 12202.5 12250.4 16:18:50 INFO - PID 1420 | -------- Summary: end -------- 16:18:50 INFO - PID 1420 | 16:18:51 INFO - TEST-INFO | 1420: exit 0 16:18:51 INFO - TEST-OK | dromaeo_css | took 1108171ms 16:18:51 INFO - TEST-START | kraken 16:18:51 INFO - Initialising browser for kraken test... 16:18:51 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57295/getInfo.html -profile /tmp/tmpPlFF8Y/profile 16:18:51 INFO - TEST-INFO | started process 1796 (/builds/slave/test/build/application/firefox/firefox http://localhost:57295/getInfo.html) 16:18:56 INFO - TEST-INFO | 1796: exit 0 16:18:56 INFO - Browser initialized. 16:18:56 INFO - Running cycle 1/1 for kraken test... 16:18:56 INFO - Using env: {'DISPLAY': ':0', 16:18:56 INFO - 'HOME': '/home/cltbld', 16:18:56 INFO - 'JSGC_DISABLE_POISONING': '1', 16:18:56 INFO - 'LANG': 'en_US.UTF-8', 16:18:56 INFO - 'LANGUAGE': 'en_US:en', 16:18:56 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:18:56 INFO - 'LOGNAME': 'cltbld', 16:18:56 INFO - 'MAIL': '/var/mail/cltbld', 16:18:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:18:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:18:56 INFO - 'MOZ_CRASHREPORTER': '1', 16:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:56 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:18:56 INFO - 'MOZ_NO_REMOTE': '1', 16:18:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:18:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:56 INFO - 'NO_EM_RESTART': '1', 16:18:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:56 INFO - 'PWD': '/builds/slave/test', 16:18:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:18:56 INFO - 'RUST_BACKTRACE': 'full', 16:18:56 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:18:56 INFO - 'SHELL': '/bin/bash', 16:18:56 INFO - 'SHLVL': '1', 16:18:56 INFO - 'TERM': 'linux', 16:18:56 INFO - 'TMOUT': '86400', 16:18:56 INFO - 'USER': 'cltbld', 16:18:56 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420', 16:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:56 INFO - '_': '/tools/buildbot/bin/python'} 16:18:56 INFO - TEST-INFO | started process 2037 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPlFF8Y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:18:57 INFO - PID 2037 | 16:18:57 INFO - PID 2037 | (/builds/slave/test/build/application/firefox/firefox:2087): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:18:57 INFO - PID 2037 | 16:19:07 INFO - PID 2037 | RSS: Main: 144523264 16:19:07 INFO - PID 2037 | 16:19:10 INFO - PID 2037 | Cycle 1(1): loaded http://localhost:57295/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57295/tests/kraken/driver.html?testName=audio-beat-detection) 16:19:10 INFO - PID 2037 | RSS: Main: 151375872 16:19:10 INFO - PID 2037 | 16:19:13 INFO - PID 2037 | Cycle 1(1): loaded http://localhost:57295/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57295/tests/kraken/driver.html?testName=audio-dft) 16:19:14 INFO - PID 2037 | RSS: Main: 151396352 16:19:14 INFO - PID 2037 | 16:19:17 INFO - PID 2037 | Cycle 1(1): loaded http://localhost:57295/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57295/tests/kraken/driver.html?testName=audio-fft) 16:19:17 INFO - PID 2037 | RSS: Main: 147726336 16:19:17 INFO - PID 2037 | 16:19:19 INFO - PID 2037 | Cycle 1(1): loaded http://localhost:57295/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57295/tests/kraken/driver.html?testName=audio-oscillator) 16:19:20 INFO - PID 2037 | RSS: Main: 148312064 16:19:20 INFO - PID 2037 | 16:19:22 INFO - PID 2037 | Cycle 1(1): loaded http://localhost:57295/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57295/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:19:22 INFO - PID 2037 | RSS: Main: 148209664 16:19:22 INFO - PID 2037 | 16:19:26 INFO - PID 2037 | Cycle 1(1): loaded http://localhost:57295/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57295/tests/kraken/driver.html?testName=imaging-darkroom) 16:19:26 INFO - PID 2037 | RSS: Main: 148455424 16:19:26 INFO - PID 2037 | 16:19:30 INFO - PID 2037 | Cycle 1(1): loaded http://localhost:57295/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57295/tests/kraken/driver.html?testName=imaging-desaturate) 16:19:30 INFO - PID 2037 | RSS: Main: 149520384 16:19:30 INFO - PID 2037 | 16:19:33 INFO - PID 2037 | Cycle 1(1): loaded http://localhost:57295/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57295/tests/kraken/driver.html?testName=json-parse-financial) 16:19:34 INFO - PID 2037 | RSS: Main: 150679552 16:19:34 INFO - PID 2037 | 16:19:35 INFO - PID 2037 | Cycle 1(1): loaded http://localhost:57295/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57295/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:19:36 INFO - PID 2037 | RSS: Main: 150560768 16:19:36 INFO - PID 2037 | 16:19:38 INFO - PID 2037 | Cycle 1(1): loaded http://localhost:57295/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57295/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:19:38 INFO - PID 2037 | RSS: Main: 150716416 16:19:38 INFO - PID 2037 | 16:19:40 INFO - PID 2037 | Cycle 1(1): loaded http://localhost:57295/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57295/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:19:41 INFO - PID 2037 | RSS: Main: 147562496 16:19:41 INFO - PID 2037 | 16:19:43 INFO - PID 2037 | Cycle 1(1): loaded http://localhost:57295/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57295/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:19:44 INFO - PID 2037 | RSS: Main: 147779584 16:19:44 INFO - PID 2037 | 16:19:47 INFO - PID 2037 | Cycle 1(1): loaded http://localhost:57295/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57295/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:19:47 INFO - PID 2037 | RSS: Main: 148701184 16:19:47 INFO - PID 2037 | 16:19:49 INFO - PID 2037 | Cycle 1(1): loaded http://localhost:57295/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57295/tests/kraken/driver.html?testName=ai-astar) 16:19:49 INFO - PID 2037 | RSS: Main: 150253568 16:19:49 INFO - PID 2037 | 16:19:49 INFO - PID 2037 | __start_tp_report 16:19:49 INFO - PID 2037 | _x_x_mozilla_page_load 16:19:49 INFO - PID 2037 | _x_x_mozilla_page_load_details 16:19:49 INFO - PID 2037 | |i|pagename|runs| 16:19:49 INFO - PID 2037 | |0;ai-astar;106;107;104;107;106;109;107;109;104;104 16:19:49 INFO - PID 2037 | |1;audio-beat-detection;113;111;111;131;115;116;113;118;122;113 16:19:49 INFO - PID 2037 | |2;audio-dft;162;162;163;165;163;163;163;164;168;168 16:19:49 INFO - PID 2037 | |3;audio-fft;74;78;73;73;74;74;76;78;82;76 16:19:49 INFO - PID 2037 | |4;audio-oscillator;79;78;78;78;79;84;83;84;78;79 16:19:49 INFO - PID 2037 | |5;imaging-gaussian-blur;111;113;110;114;114;114;114;114;114;114 16:19:49 INFO - PID 2037 | |6;imaging-darkroom;152;151;151;151;150;150;153;150;151;150 16:19:49 INFO - PID 2037 | |7;imaging-desaturate;94;96;94;94;95;94;94;96;94;94 16:19:49 INFO - PID 2037 | |8;json-parse-financial;47;44;45;43;44;46;46;49;45;45 16:19:49 INFO - PID 2037 | |9;json-stringify-tinderbox;39;39;39;39;39;39;40;41;39;39 16:19:49 INFO - PID 2037 | |10;stanford-crypto-aes;71;72;70;70;71;70;75;79;71;72 16:19:49 INFO - PID 2037 | |11;stanford-crypto-ccm;103;106;109;111;106;104;110;108;107;107 16:19:49 INFO - PID 2037 | |12;stanford-crypto-pbkdf2;154;154;152;152;152;158;150;152;155;155 16:19:49 INFO - PID 2037 | |13;stanford-crypto-sha256-iterative;60;61;63;63;61;61;61;63;61;61 16:19:49 INFO - PID 2037 | __end_tp_report 16:19:49 INFO - PID 2037 | __start_cc_report 16:19:49 INFO - PID 2037 | _x_x_mozilla_cycle_collect,700 16:19:49 INFO - PID 2037 | __end_cc_report 16:19:49 INFO - PID 2037 | __startTimestamp1506122389668__endTimestamp 16:19:49 INFO - PID 2037 | 16:19:49 INFO - PID 2037 | ------- Summary: start ------- 16:19:49 INFO - PID 2037 | Number of tests: 14 16:19:49 INFO - PID 2037 | 16:19:49 INFO - PID 2037 | [#0] ai-astar Cycles:10 Average:106.30 Median:107.00 stddev:1.89 (1.8%) stddev-sans-first:2.00 16:19:49 INFO - PID 2037 | Values: 106.0 107.0 104.0 107.0 106.0 109.0 107.0 109.0 104.0 104.0 16:19:49 INFO - PID 2037 | 16:19:49 INFO - PID 2037 | [#1] audio-beat-detection Cycles:10 Average:116.30 Median:115.50 stddev:6.17 (5.3%) stddev-sans-first:6.42 16:19:49 INFO - PID 2037 | Values: 113.0 111.0 111.0 131.0 115.0 116.0 113.0 118.0 122.0 113.0 16:19:49 INFO - PID 2037 | 16:19:49 INFO - PID 2037 | [#2] audio-dft Cycles:10 Average:164.10 Median:163.50 stddev:2.23 (1.4%) stddev-sans-first:2.24 16:19:49 INFO - PID 2037 | Values: 162.0 162.0 163.0 165.0 163.0 163.0 163.0 164.0 168.0 168.0 16:19:49 INFO - PID 2037 | 16:19:49 INFO - PID 2037 | [#3] audio-fft Cycles:10 Average:75.80 Median:76.00 stddev:2.86 (3.8%) stddev-sans-first:2.96 16:19:49 INFO - PID 2037 | Values: 74.0 78.0 73.0 73.0 74.0 74.0 76.0 78.0 82.0 76.0 16:19:49 INFO - PID 2037 | 16:19:49 INFO - PID 2037 | [#4] audio-oscillator Cycles:10 Average:80.00 Median:79.00 stddev:2.58 (3.3%) stddev-sans-first:2.71 16:19:49 INFO - PID 2037 | Values: 79.0 78.0 78.0 78.0 79.0 84.0 83.0 84.0 78.0 79.0 16:19:49 INFO - PID 2037 | 16:19:49 INFO - PID 2037 | [#5] imaging-gaussian-blur Cycles:10 Average:113.20 Median:114.00 stddev:1.48 (1.3%) stddev-sans-first:1.33 16:19:49 INFO - PID 2037 | Values: 111.0 113.0 110.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 16:19:49 INFO - PID 2037 | 16:19:49 INFO - PID 2037 | [#6] imaging-darkroom Cycles:10 Average:150.90 Median:151.00 stddev:0.99 (0.7%) stddev-sans-first:0.97 16:19:49 INFO - PID 2037 | Values: 152.0 151.0 151.0 151.0 150.0 150.0 153.0 150.0 151.0 150.0 16:19:49 INFO - PID 2037 | 16:19:49 INFO - PID 2037 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.00 stddev:0.85 (0.9%) stddev-sans-first:0.88 16:19:49 INFO - PID 2037 | Values: 94.0 96.0 94.0 94.0 95.0 94.0 94.0 96.0 94.0 94.0 16:19:49 INFO - PID 2037 | 16:19:49 INFO - PID 2037 | [#8] json-parse-financial Cycles:10 Average:45.40 Median:45.50 stddev:1.71 (3.8%) stddev-sans-first:1.72 16:19:49 INFO - PID 2037 | Values: 47.0 44.0 45.0 43.0 44.0 46.0 46.0 49.0 45.0 45.0 16:19:49 INFO - PID 2037 | 16:19:49 INFO - PID 2037 | [#9] json-stringify-tinderbox Cycles:10 Average:39.30 Median:39.00 stddev:0.67 (1.7%) stddev-sans-first:0.71 16:19:49 INFO - PID 2037 | Values: 39.0 39.0 39.0 39.0 39.0 39.0 40.0 41.0 39.0 39.0 16:19:49 INFO - PID 2037 | 16:19:49 INFO - PID 2037 | [#10] stanford-crypto-aes Cycles:10 Average:72.10 Median:71.50 stddev:2.85 (4.0%) stddev-sans-first:2.99 16:19:49 INFO - PID 2037 | Values: 71.0 72.0 70.0 70.0 71.0 70.0 75.0 79.0 71.0 72.0 16:19:49 INFO - PID 2037 | 16:19:49 INFO - PID 2037 | [#11] stanford-crypto-ccm Cycles:10 Average:107.10 Median:107.50 stddev:2.51 (2.3%) stddev-sans-first:2.19 16:19:49 INFO - PID 2037 | Values: 103.0 106.0 109.0 111.0 106.0 104.0 110.0 108.0 107.0 107.0 16:19:49 INFO - PID 2037 | 16:19:49 INFO - PID 2037 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.40 Median:154.00 stddev:2.27 (1.5%) stddev-sans-first:2.40 16:19:49 INFO - PID 2037 | Values: 154.0 154.0 152.0 152.0 152.0 158.0 150.0 152.0 155.0 155.0 16:19:49 INFO - PID 2037 | 16:19:49 INFO - PID 2037 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:61.00 stddev:1.08 (1.8%) stddev-sans-first:1.00 16:19:49 INFO - PID 2037 | Values: 60.0 61.0 63.0 63.0 61.0 61.0 61.0 63.0 61.0 61.0 16:19:49 INFO - PID 2037 | -------- Summary: end -------- 16:19:49 INFO - PID 2037 | 16:19:49 INFO - PID 2037 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:19:50 INFO - TEST-INFO | 2037: exit 0 16:19:50 INFO - TEST-OK | kraken | took 59125ms 16:19:50 INFO - SUITE-END | took 1167s 16:19:50 INFO - Completed test suite (00:19:27) 16:19:50 INFO - javascript benchmark 16:19:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9092.053058675157, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3825.7321987460887, 3834.7507412018217, 3870.3226935286316, 3880.980595097025, 3883.203943191646, 6062.969685151579, 6262.874742505147, 6286.308506064329, 6295.905561416583, 6403.839904002396, 6270.905936410946, 6345.428911397973, 6347.5239357048295, 6371.776987805428, 6381.968090159543, 5563.443655634444, 5593.2449119368775, 5660.688662123582, 5666.008448521513, 5691.117876729093, 5631.042722737145, 5669.177969194464, 5688.402717714643, 5735.168400581913, 5750.166225897243, 5670.518005969477, 5697.77208911643, 5797.246357973458, 5800.738966746486, 5804.071348584227, 5493.752781124819, 5637.0, 5689.516391106719, 5713.14302854571, 5720.2849643794525, 5641.182028605829, 5656.490915817555, 5706.201131841545, 5709.257796486471, 5714.0, 6129.356417576179, 6236.095766113888, 6256.124142620037, 6285.717142728588, 6367.140436041122, 6740.0, 6761.966190169067, 6768.49236307274, 6776.864462710768, 6842.897356539655, 6825.48808839339, 6879.5184337095925, 6899.206591242021, 6903.965480172567, 6912.481563882729, 6308.873822523524, 6327.873442531123, 6348.9524228502705, 6355.650439225795, 6423.871522569522, 6931.618760968095, 6960.443164546926, 7022.40309573692, 7050.541714788625, 7062.505624606229, 6552.082708420825, 6686.3313668632745, 6737.663116844236, 6764.864702705919, 6770.4922130839395, 6839.076724642109, 6851.143607049119, 6851.588904665637, 6861.55399899005, 6868.072810170663, 6599.043138744855, 6618.0, 6691.799246022627, 6696.966515167393, 6704.195496540349, 6669.533132680665, 6733.293004234485, 6789.592624442549, 6809.182898052265, 6823.249442561314, 4956.306117143603, 4995.750212489434, 4996.6502344836235, 4997.525235102659, 5009.44896061433, 4525.343825145335, 4533.274676051816, 4559.726416415026, 4571.9085618287445, 4578.0, 6223.066540019034, 6223.875522489616, 6242.063690446378, 6302.306746257908, 6314.905276420857, 6601.636909969998, 6734.528582999241, 6741.1236539249585, 6901.689423975933, 6907.34380233877, 6218.720157592919, 6220.626762394271, 6249.0938813871735, 6257.530685198628, 6271.560990730696, 5460.863478413072, 5475.06923822951, 5529.917051244234, 5548.361938377057, 5549.639273447213, 6899.310068993061, 6930.653467326613, 6945.652717364112, 6991.161060672752, 7033.788986330418, 6775.796726098225, 6777.525573209927, 6858.897116543356, 6893.586384816927, 6894.655267236719, 6993.19578248508, 7008.123984501937, 7083.574985500681, 7089.574625522485, 7091.751788687371, 6685.064091027163, 6686.732530698718, 6718.798436046926, 6739.93260067393, 6803.965980170067, 6872.347127022925, 6921.1002569667635, 6933.2720064392515, 6939.410150137295, 6940.652967351713, 6547.770828020996, 6607.174103237096, 6617.305182955913, 6695.832604184934, 6701.530892837454, 6582.868342633216, 6583.275839657538, 6663.8667226654225, 6668.366505182, 6697.0, 6494.2856285807575, 6566.4418524425955, 6582.670866456754, 6586.2755096940255, 6596.241432235162, 6666.899996499959, 6674.365935236145, 6693.163354580677, 6760.1549806274215, 6781.593104413751, 6364.331745166691, 6436.227652681709, 6492.513061520311, 6493.090967264492, 6530.379613936669], "value": 6223.094287114495, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [963.1091240602443, 987.5555999800092, 988.2983082011772, 993.0913214408816, 993.3841018568486, 40991.15539800709, 44649.55350446499, 45355.0928981421, 45370.77314613426, 45433.0, 43387.13225735483, 43530.0, 43670.56329436705, 43735.343969840476, 43888.0, 7791.610419479031, 7891.447598668095, 7903.762887113396, 7905.644246008944, 7914.920850791505, 7733.342665873408, 7833.96083019587, 7879.8424031519335, 7880.724174653888, 7895.447318687666, 7841.3726901848, 7870.803229919269, 7898.684052637887, 7902.407319451036, 7902.486338388015, 7764.3012128908385, 8370.288525475342, 8673.566321683922, 8675.95662021692, 8682.26200772935, 8420.621072051772, 8441.240288374076, 8493.36299777513, 8504.872426913602, 8505.617247223829, 41514.0, 41890.0, 42205.0, 42746.572534274885, 42850.0, 3707.870224542128, 3742.644448777362, 3744.9438258426144, 3756.9248615027545, 3758.342290099222, 34074.829625851715, 34256.14359641004, 34348.82825585856, 34516.0, 34567.308653827036, 5306.071437498461, 5307.628466007381, 5326.414094449608, 5330.626856120073, 5344.35867695879, 211106.0, 213576.0, 213653.0, 214177.0, 215381.99999999843, 46656.066878662234, 47550.5244947553, 51414.22878656823, 51758.48241517612, 52201.2169817453, 54461.72769136208, 54515.72742136264, 54563.18155227675, 54589.45410545844, 54635.45364546383, 16221.918890405708, 16263.837361626469, 16293.429729959395, 16339.101349425895, 16354.918225409036, 13045.34773261333, 13050.804237936438, 13079.41142648583, 13085.0, 13325.800112998313, 14468.131912085099, 14504.129752214902, 14594.270286485633, 14604.561863144123, 14757.77863332051, 5616.326040875121, 5742.397048309951, 5761.711914404263, 5778.162166485836, 5923.259592550931, 1078.455380033094, 1093.2347356850078, 1107.5680484611007, 1124.4883577972166, 1127.7969965406305, 25875.48249035009, 25918.22245332643, 26262.737372626412, 26320.0, 26488.735112649014, 1208.5528354508713, 1223.4005337384617, 1224.6693392783898, 1234.7592219517287, 1241.4351470081092, 13690.93154534221, 13859.792103118461, 13884.0, 14121.0, 14210.928945355206, 272066.0, 272255.0, 273799.0, 274290.0, 274348.0, 58310.125348119815, 58523.0, 58771.41228587745, 64505.0, 65415.0, 1510.2750679673818, 1533.4173014254511, 1538.4000239906445, 1540.4454396417282, 1546.0, 31174.688253118085, 31321.216969575944, 31740.682593173773, 32185.195370114998, 32482.35035299328, 44036.11927761491, 44275.0, 44339.77830110829, 44369.778151109036, 44431.0, 256944.0, 257029.0, 257291.0, 257443.0, 258066.0, 38471.80764096162, 38553.0, 39330.0, 39349.40975885421, 39377.40933886051, 47064.7646761764, 48039.519604804904, 48677.2698409517, 48754.26868596903, 48821.0, 35257.76597819064, 35322.470162947066, 35417.16662667032, 35489.290214195054, 36292.0, 35630.0, 35642.148628110204, 36092.0, 36126.819365903, 36605.26789464142, 41152.0, 41252.793736031126, 41884.58115418807, 41939.0, 41942.161156776085, 34271.0, 34603.82698086594, 34938.30123397569, 35087.12282192965, 35229.471557927165, 25685.16602436275, 25985.350366240997, 26334.20997370082, 26719.332016699736, 26821.0], "value": 18873.197904901208, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.7671646416128, 610.2219669920852, 619.2630769384432, 624.6470744029623, 626.2422468812737, 22621.773782262193, 23377.064917403306, 23538.529229415424, 23561.64657530134, 23586.056557737713, 23232.535349293004, 24899.0, 24961.87519062407, 24974.125905593308, 25017.0, 6773.796786096413, 6925.65371731414, 6983.092198014251, 6988.266232045638, 6997.475189360793, 6682.799516014527, 6857.211420686635, 6919.688614012382, 6963.09479767632, 6967.059446974669, 6887.242403335629, 6891.310868913119, 6915.723371065176, 6922.619255940921, 6925.0, 6511.0, 6797.966010169967, 6801.081853949728, 6803.047573339736, 6813.569150478405, 6363.713632886531, 6366.713497892606, 6375.744970201186, 6376.0, 6379.266384365811, 30669.386612267852, 30837.38325233483, 30992.845035774902, 31016.53475197874, 31064.0, 2240.19353032908, 2288.645259984705, 2297.1959814065112, 2299.54009198161, 2300.5628930503317, 20234.392968210977, 20974.16103355864, 21013.159473621035, 21017.789822101735, 21029.89485052565, 2524.507720994407, 2540.8602526861014, 2586.172424824057, 2653.2968763277745, 2665.5335316319565, 138921.3053934734, 146125.26937365351, 146600.0, 152427.0, 159348.4065159345, 16191.433299834685, 16863.15684215784, 17179.226934787963, 17242.741358879626, 17291.82708172902, 26617.201483955512, 26708.59937100945, 26756.197314080608, 26822.0, 26923.326916826845, 10652.360858348524, 10713.946430267799, 10839.729006774736, 10954.56181752721, 11003.669889903316, 9798.412095274307, 9833.950830245803, 9855.80288394228, 9897.80204395908, 9959.05388988045, 10132.645357412455, 10169.96600679867, 10200.3369780964, 10329.276950613536, 10523.0, 2979.4041191761735, 2996.5654980027775, 3075.7231849133686, 3122.3919681363664, 3141.293209027996, 1022.4172221833484, 1037.5331101004579, 1038.2991480750613, 1053.146950969717, 1054.5323941975814, 21855.344339669835, 21991.0, 22161.889190554266, 22216.22243221512, 22468.21361252381, 1085.719988025141, 1104.6685994201707, 1105.6075093341908, 1112.3548341961643, 1123.1407972900738, 11065.336079835235, 11911.880881191251, 11914.225575337749, 12012.459439325252, 12068.456919438646, 595917.0, 599682.0, 609945.0, 613936.0, 615204.0, 124828.37585812013, 127245.36377318054, 130600.0, 130835.0, 131381.0, 1213.466074927047, 1215.0644004116957, 1242.465739731924, 1245.6574442028334, 1251.249250449741, 19015.64654397245, 19107.713384299524, 19370.322038728576, 19568.21727130899, 19744.308949187285, 59028.0, 60000.39999600123, 61143.38856611555, 61170.38829611647, 61993.38006619876, 75259.24740752522, 75396.62301688457, 76198.61900690461, 76471.61764191143, 76733.0, 15797.605059873595, 15875.12686802237, 15931.203439828194, 16082.517524474284, 16106.436274730328, 23783.64324535168, 23998.52002959896, 24123.879380602986, 24294.757052429955, 24470.877645611657, 19522.121504532046, 19644.60710785805, 19831.60336793227, 19846.801531984496, 19889.900550497154, 17104.657906841545, 17198.31206751716, 17490.650186996445, 17583.0, 17723.657703862635, 19292.45660347197, 19337.0, 19346.0, 19422.708659370404, 19553.21787128499, 15514.301856416263, 15570.377184912477, 15746.9212653936, 15799.815013874235, 15837.366505339658, 19689.310874119335, 19774.90112549428, 20148.69033512817, 20305.390838274874, 20349.49126271855], "value": 12884.419236410638, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1427.157976793692, 1512.5991612222763, 1514.7349213887574, 1528.4039224702376, 1530.395493779957, 16581.170941452925, 17315.91342043288, 17346.653066938652, 17356.652866942684, 17373.131343432808, 17025.744613830804, 17089.57276068099, 17199.312027518885, 17227.05251211183, 17232.56918577036, 2861.1273561563717, 2913.0, 2920.2261400728826, 2946.0867131189293, 2946.4107178564263, 2848.914532564016, 2873.6264285642837, 2896.666883308415, 2900.361920377514, 2902.7097290271017, 2711.3628297350106, 2822.2379957411486, 2956.231379841235, 2962.1261727790247, 2967.5845381646586, 2827.4486475137355, 2856.128880691388, 2865.126136528358, 2879.093085678015, 2883.9134825955257, 2762.350847550824, 2794.203651959182, 2799.0902956539207, 2810.6065150878894, 2847.900323488668, 16705.91647041769, 16971.66056678872, 16979.915100424543, 16985.49043528696, 17004.574885627833, 8105.594720263964, 8289.626966786509, 8381.664733410656, 8384.916150838535, 8392.328613710888, 5336.573074154058, 5487.506124448779, 5566.972165139189, 5619.522340601054, 5627.971860140714, 2136.102836808544, 2162.4701948022835, 2163.5348400094053, 2185.5628874225217, 2222.7777222277805, 31092.689073109206, 31241.218969525715, 31383.37233255345, 31410.528842067386, 31416.99999999977, 7420.480566360303, 7524.924750752422, 7553.8866916996285, 7692.692292308357, 7710.383169346439, 20080.59838803216, 20129.79870201279, 20163.899180504002, 20164.495887602632, 20874.47813804637, 8440.15598440151, 8587.871181932276, 8681.2186903179, 8689.304855611535, 8694.782630434163, 7597.886031709529, 7748.690052397954, 7780.0, 7822.022247219098, 7880.093789214199, 5532.225488431623, 5732.05421105513, 5734.139879018181, 5771.105478650816, 5823.56323275756, 1679.5381270150563, 1725.887817291872, 1734.4276388791668, 1742.8867123636924, 1770.2211027148014, 1003.0, 1020.9387436753818, 1025.0927928782933, 1025.374521541859, 1044.7074819050679, 8700.738977830675, 8828.51443170619, 8839.071897450804, 8933.062028487046, 8990.775230619287, 815.3069890592949, 817.2603793566832, 822.8436597046542, 828.5401602110766, 832.0888626953476, 5205.7397130143345, 5466.070767969455, 5525.972370138204, 5732.140178973104, 6130.478909292671, 23924.282271531883, 25536.23391298227, 25762.227133186036, 25819.225423237334, 25839.0, 23790.64314035291, 25093.74906250914, 25169.867355969025, 25483.10809121709, 25502.872485637825, 12587.874121258854, 12694.746105078031, 12797.23216606988, 12822.487100515875, 12837.0, 9315.0, 9414.2468602513, 9429.05709429079, 9430.764230894009, 9442.48066356357, 20904.1638334471, 21985.890070549543, 22059.11763529441, 22228.88885555562, 22259.443513912283, 21637.675434868175, 22121.225757098426, 22614.0, 22621.0, 22643.773562264825, 12554.43505042257, 12613.36933153357, 12715.364231788559, 12730.427130778951, 12747.93626031901, 14788.260586970824, 15119.697606048037, 15146.545603631908, 15154.469593564172, 15165.469208577646, 11739.647810565697, 11898.345590992578, 11943.641690749291, 12023.819642705192, 12221.633350999484, 12604.936975315064, 12631.621051368473, 12716.491340346653, 12734.0, 13149.53976610814, 10147.0, 10305.227107967083, 10406.167506599302, 10420.895791041992, 10422.947885260524, 7957.56233407145, 8036.437449378721, 8143.51138931666, 8149.9185008151535, 8160.5103693778565, 8111.75664730059, 8134.308583770446, 8209.79475513117, 8320.0, 8376.958115209385], "value": 7339.839415250157, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.1137425616039, 569.7066011004332, 576.5704550110167, 593.7713980117653, 599.5143933413935, 6340.270868850078, 6367.49060075194, 6383.138276332694, 6392.808215753529, 6403.367141378948, 6125.663088530129, 6258.593191442565, 6288.308286088538, 6295.181626388575, 6322.968385158079, 4930.211166213407, 5043.193089105744, 5075.923861142086, 5146.794128234867, 5162.432132465435, 5012.398512178544, 5038.0, 5054.620903432239, 5057.848264552069, 5068.5438310552045, 5021.748912554358, 5023.120953833083, 5031.163625276766, 5034.697918124924, 5034.748262586856, 4965.205567109263, 5001.17480615698, 5010.674306170124, 5022.17134172861, 5043.52086551777, 4606.723596584216, 4620.283856002325, 4620.930686039712, 4623.144718227139, 4627.398438203012, 6123.846903827395, 6133.6626485543275, 6221.751129954796, 6268.717907694165, 6289.811305660837, 5869.0, 5898.321693005317, 5907.616004959664, 5923.229980102602, 5942.286925568917, 6662.56693314933, 6692.866142677168, 6714.496412769062, 6742.898856517156, 6758.1752926710005, 4818.060478206751, 5114.079465696173, 5127.66670166438, 5145.948540514584, 5153.845384638467, 6788.253292137861, 6807.693653785592, 6845.828854278633, 6863.725450982005, 6905.723771049152, 5171.637985341065, 5216.739163041832, 5219.660722053054, 5231.503007214308, 5232.717984093922, 5251.947480525146, 5288.233206185082, 5294.92057619136, 5298.834256463572, 5306.46935306474, 5782.45066718661, 5792.971035144881, 5821.446962538552, 5855.648661080264, 5902.3507414184405, 6680.5323627345615, 6695.192298079589, 6695.765648202289, 6732.697028633724, 6774.153230846145, 1808.9366872159412, 1813.6372725454962, 1830.2130084063715, 1837.1549087419937, 1849.4913898677971, 1241.5654520917606, 1296.6499045257729, 1303.4134639412302, 1316.493150137204, 1317.1833463252653, 5920.407959204045, 5925.970370148208, 5963.880722385528, 6004.159417681528, 6067.45392914631, 6543.509236807258, 6560.376764207393, 6602.207735071822, 6609.239937407259, 6709.7316107355155, 6076.969615151896, 6140.618360868769, 6166.0, 6189.319174890758, 6206.0, 3497.370473314777, 3515.1739341254784, 3544.911377215539, 3570.0539357070397, 3576.818140934028, 6957.339052789976, 7126.5034342788595, 7129.215786263507, 7135.572885422936, 7145.535540189871, 6930.410915072174, 6941.965290173618, 6958.165020197609, 6968.198657154492, 7001.50989430735, 6958.269381715049, 7069.505134640629, 7125.539264450776, 7134.714611415485, 7159.534630249122, 6759.222689390783, 6771.255161932085, 6788.93211067883, 6810.0, 6831.760888368884, 6614.503912206507, 6719.596824190564, 6728.697208625722, 6777.457803375817, 6845.418139458003, 7003.4397248219, 7034.824129396806, 7069.929300706927, 7127.679254433667, 7160.606166660884, 5131.307273518028, 5233.790648374174, 5266.42069372361, 5278.9472105278455, 5287.9735601321745, 6594.703238354382, 6756.69594868242, 6822.897656535255, 6885.139357580302, 6911.0, 6497.610143391412, 6626.734930602915, 6638.668066596554, 6673.366030227121, 6676.599404035774, 6613.834654133492, 6616.503762217949, 6645.634490103091, 6662.533622646368, 6686.264510903699, 6446.742130314735, 6474.935250647622, 6481.157449531531, 6511.218653761579, 6542.182227221598, 6370.203724534434, 6575.769848055295, 6595.88931541902, 6631.469482441489, 6691.130153080068, 6352.650604216812, 6372.745090196526, 6398.424141827258, 6458.192725909262, 6523.24982626985], "value": 5208.582203541502, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [954.3415043619906, 988.560090759612, 997.3666721631766, 997.9870431512012, 1012.8683271174743, 19615.509612259702, 20111.698324525143, 20140.899295503503, 20158.496037599067, 20158.89920550399, 18275.0, 18329.0, 18552.25790968363, 18621.534461638465, 18746.0, 1062.1768129699506, 1087.809633314171, 1088.5264909764278, 1089.3463921647028, 1090.0080926357016, 1067.4235912607182, 1083.6694808083532, 1097.8408130821067, 1098.0392156862745, 1098.2092893116942, 1130.5760339872547, 1131.4003578103577, 1131.835883796849, 1137.385811661706, 1142.4173671427548, 1129.0967226219075, 1131.5021390588156, 1141.2866958151155, 1144.1247445704043, 1150.8388825564427, 797.3979645367768, 812.8536863364592, 818.2880893622527, 818.7298191596759, 818.8894499242585, 21077.26229581957, 21419.0, 21441.356759297247, 21448.678269825967, 21458.0, 22231.888840555694, 22572.322830315115, 23243.302700919, 23259.0, 23412.180573679838, 18632.627347452973, 18728.625427491374, 18862.056897155224, 18959.24163033477, 18962.71555926662, 3910.706696997708, 3954.0510277533185, 3955.367141257414, 3956.188981258835, 3957.3668213086057, 156706.0, 157231.0, 157548.0, 158603.0, 161074.0, 17375.131243437776, 17423.128843558025, 18078.5480362992, 18162.09189540544, 18382.540436489195, 33546.3290734184, 34238.82880585581, 34305.313893721985, 34378.0, 34832.0, 9096.317776166681, 9187.402818816756, 9223.53882305882, 9239.861402078974, 9256.629734810667, 4623.745693986863, 4719.598834099072, 4764.642651801059, 4765.904681906342, 4767.594754445841, 5655.0, 5917.082852157922, 5941.881162376728, 5952.255968003999, 5957.195778569924, 1040.0743338428804, 1073.88187299397, 1081.8214994525815, 1082.8700555933337, 1083.6261489786011, 727.1710250314645, 745.6048294403975, 754.3738696881575, 765.4871236271712, 774.2915232562256, 12453.626391208278, 12462.43919023646, 12668.303243321521, 13101.344932753324, 13412.262325572186, 725.6190499987443, 736.4550232827669, 738.7045181927272, 739.4638886807022, 747.8504299140194, 6204.348543402968, 6262.2172228471445, 6294.842628934313, 6352.587081839665, 6447.6453795041725, 148685.0, 152415.2379238119, 152564.0, 152952.0, 155179.0, 133547.33226333806, 133929.0, 135431.3228433871, 136415.31792340975, 139822.0, 109051.0, 110238.0, 111024.44487777671, 111175.0, 112315.0, 11517.0, 11678.591249306573, 11831.290122592673, 11935.701607459536, 12047.638570842539, 56394.7180264096, 56503.434965649816, 57092.714536427055, 57370.426295736506, 59043.40956590379, 70680.93978590223, 72300.0, 72314.0, 73098.0, 73385.6330718343, 15627.296771645077, 16525.83474165243, 16613.41853035138, 16638.83361166373, 16653.666926661157, 16965.23656435488, 17376.304947801946, 17441.56396090108, 17447.127643618023, 17576.0, 16648.417305394254, 16752.916235418747, 16848.07335596554, 16853.915730421268, 16961.83038169652, 16059.598510037342, 16158.757618635966, 16374.263158157673, 16375.59061023484, 16608.418705345255, 15342.15618141013, 15344.156071416179, 15519.068855868683, 15534.1456219909, 15587.532374028797, 12534.811977820522, 12610.43253053633, 12752.298623575793, 12952.28762418076, 13182.538611148564, 12046.578369757017, 12186.573469928864, 12188.49545610901, 12202.511899523919, 12250.448729807002], "value": 9763.871028381822, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1379.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 107.0, 104.0, 107.0, 106.0, 109.0, 107.0, 109.0, 104.0, 104.0], "value": 106.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 111.0, 131.0, 115.0, 116.0, 113.0, 118.0, 122.0, 113.0], "value": 116.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 163.0, 165.0, 163.0, 163.0, 163.0, 164.0, 168.0, 168.0], "value": 164.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 78.0, 73.0, 73.0, 74.0, 74.0, 76.0, 78.0, 82.0, 76.0], "value": 75.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 78.0, 78.0, 79.0, 84.0, 83.0, 84.0, 78.0, 79.0], "value": 80.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 110.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 113.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 151.0, 151.0, 150.0, 150.0, 153.0, 150.0, 151.0, 150.0], "value": 150.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 94.0, 94.0, 95.0, 94.0, 94.0, 96.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 44.0, 45.0, 43.0, 44.0, 46.0, 46.0, 49.0, 45.0, 45.0], "value": 45.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 39.0, 39.0, 39.0, 39.0, 39.0, 40.0, 41.0, 39.0, 39.0], "value": 39.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 70.0, 70.0, 71.0, 70.0, 75.0, 79.0, 71.0, 72.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 106.0, 109.0, 111.0, 106.0, 104.0, 110.0, 108.0, 107.0, 107.0], "value": 107.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 154.0, 152.0, 152.0, 152.0, 158.0, 150.0, 152.0, 155.0, 155.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 63.0, 63.0, 61.0, 61.0, 61.0, 63.0, 61.0, 61.0], "value": 61.5, "unit": "score"}]}]} 16:19:50 INFO - Return code: 0 16:19:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:19:50 INFO - # TBPL SUCCESS # 16:19:50 INFO - Running post-action listener: _package_coverage_data 16:19:50 INFO - Running post-action listener: _resource_record_post_action 16:19:50 INFO - [mozharness: 2017-09-22 23:19:50.480939Z] Finished run-tests step (success) 16:19:50 INFO - Running post-run listener: _resource_record_post_run 16:19:50 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 8192; Write bytes: 323371008; Read time: 180; Write time: 223716 16:19:50 INFO - TinderboxPrint: CPU usage
13.1% 16:19:50 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 180 16:19:50 INFO - TinderboxPrint: I/O write bytes / time
323,371,008 / 223,716 16:19:50 INFO - TinderboxPrint: CPU idle
8,212.8 (86.9%) 16:19:50 INFO - TinderboxPrint: CPU user
1,187.6 (12.6%) 16:19:50 INFO - TinderboxPrint: Swap in / out
0 / 0 16:19:50 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12484608; Read time: 0; Write time: 1940 16:19:50 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:19:50 INFO - run-tests - Wall time: 1168s; CPU: 13.0%; Read bytes: 8192; Write bytes: 309854208; Read time: 180; Write time: 221748 16:19:51 INFO - Running post-run listener: _upload_blobber_files 16:19:51 INFO - Blob upload gear active. 16:19:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:19:51 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 16:19:51 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'] 16:19:51 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 16:19:51 INFO - (blobuploader) - INFO - Open directory for files ... 16:19:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:19:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:19:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:19:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:19:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:19:52 INFO - (blobuploader) - INFO - Done attempting. 16:19:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:19:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:19:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:19:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:19:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:19:59 INFO - (blobuploader) - INFO - Done attempting. 16:19:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:19:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:19:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:20:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:20:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:20:00 INFO - (blobuploader) - INFO - Done attempting. 16:20:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:20:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:20:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:20:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:20:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:20:01 INFO - (blobuploader) - INFO - Done attempting. 16:20:01 INFO - (blobuploader) - INFO - Iteration through files over. 16:20:01 INFO - Return code: 0 16:20:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:20:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:20:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4bb1d08bdfe5d988ebb64278f21471c5b23ccccbb21c3c37cd3e0d9963b4bdcf50f37253546345f7490ebcb594ae72bd355ee0a29823db255aac5c4fa4060f8c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f338df1d6eff72e0e8b4546d9fe348e6d9c80756b261de1b2268e7c4bbee62ea9df130add21f36830b02e7304ae0b30cfeb35d5fc4e0706b2ab6b535cf6ecfc1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c063532489499a5e39c81df2e9b91c91fa5a6b3391ace8fc6dffffad18776c3c8bb74c2c9c907f510e7f56678c49af8f58b467cacec6e4e5cc9179821bc34e10", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d0ebb00be89c813be2e0703388ddb5fc4812602fd36fd6c03e8bd1505371b249e3ac9204966f5d5fa19dbfea545489b8b7b38e6e58305ef3ab636eed76e48ec"} 16:20:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:20:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:20:01 INFO - Contents: 16:20:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4bb1d08bdfe5d988ebb64278f21471c5b23ccccbb21c3c37cd3e0d9963b4bdcf50f37253546345f7490ebcb594ae72bd355ee0a29823db255aac5c4fa4060f8c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f338df1d6eff72e0e8b4546d9fe348e6d9c80756b261de1b2268e7c4bbee62ea9df130add21f36830b02e7304ae0b30cfeb35d5fc4e0706b2ab6b535cf6ecfc1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c063532489499a5e39c81df2e9b91c91fa5a6b3391ace8fc6dffffad18776c3c8bb74c2c9c907f510e7f56678c49af8f58b467cacec6e4e5cc9179821bc34e10", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d0ebb00be89c813be2e0703388ddb5fc4812602fd36fd6c03e8bd1505371b249e3ac9204966f5d5fa19dbfea545489b8b7b38e6e58305ef3ab636eed76e48ec"} 16:20:01 INFO - Running post-run listener: copy_logs_to_upload_dir 16:20:01 INFO - Copying logs to upload dir... 16:20:01 INFO - mkdir: /builds/slave/test/build/upload/logs 16:20:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.626695 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-09-22 16:20:01.769994) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-22 16:20:01.775143) ========= 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=ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4bb1d08bdfe5d988ebb64278f21471c5b23ccccbb21c3c37cd3e0d9963b4bdcf50f37253546345f7490ebcb594ae72bd355ee0a29823db255aac5c4fa4060f8c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f338df1d6eff72e0e8b4546d9fe348e6d9c80756b261de1b2268e7c4bbee62ea9df130add21f36830b02e7304ae0b30cfeb35d5fc4e0706b2ab6b535cf6ecfc1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c063532489499a5e39c81df2e9b91c91fa5a6b3391ace8fc6dffffad18776c3c8bb74c2c9c907f510e7f56678c49af8f58b467cacec6e4e5cc9179821bc34e10", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d0ebb00be89c813be2e0703388ddb5fc4812602fd36fd6c03e8bd1505371b249e3ac9204966f5d5fa19dbfea545489b8b7b38e6e58305ef3ab636eed76e48ec"} build_url:https://queue.taskcluster.net/v1/task/dkFSWy0DRHCR9Q0gzCA1Rg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011384 build_url: 'https://queue.taskcluster.net/v1/task/dkFSWy0DRHCR9Q0gzCA1Rg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4bb1d08bdfe5d988ebb64278f21471c5b23ccccbb21c3c37cd3e0d9963b4bdcf50f37253546345f7490ebcb594ae72bd355ee0a29823db255aac5c4fa4060f8c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f338df1d6eff72e0e8b4546d9fe348e6d9c80756b261de1b2268e7c4bbee62ea9df130add21f36830b02e7304ae0b30cfeb35d5fc4e0706b2ab6b535cf6ecfc1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c063532489499a5e39c81df2e9b91c91fa5a6b3391ace8fc6dffffad18776c3c8bb74c2c9c907f510e7f56678c49af8f58b467cacec6e4e5cc9179821bc34e10", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d0ebb00be89c813be2e0703388ddb5fc4812602fd36fd6c03e8bd1505371b249e3ac9204966f5d5fa19dbfea545489b8b7b38e6e58305ef3ab636eed76e48ec"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-22 16:20:01.813459) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 16:20:01.813804) ========= 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=ca6c2627acea03e666270f68000001e0-1506121083.605046-1372185420 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004476 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 16:20:01.898008) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-22 16:20:01.898352) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-22 16:20:01.901778) ========= ========= Total master_lag: 0.25 =========