builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-088 starttime: 1505956809.22 results: success (0) revision: f8dd3f21e434be32fe5901849f1723b0e64cf668 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-20 18:20:09.219202) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-20 18:20:09.219670) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-20 18:20:09.219966) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-20 18:20:09.240867) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:20:09.241240) ========= 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=9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-20 18:20:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2017-09-20 18:20:09 (46.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122471 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:20:09.382781) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:20:09.383102) ========= 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=9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015958 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:20:09.435820) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:20:09.436176) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f8dd3f21e434be32fe5901849f1723b0e64cf668 --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 f8dd3f21e434be32fe5901849f1723b0e64cf668 --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=9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-20 18:20:09,288 truncating revision to first 12 chars 2017-09-20 18:20:09,288 Setting DEBUG logging. 2017-09-20 18:20:09,288 attempt 1/10 2017-09-20 18:20:09,288 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f8dd3f21e434?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-20 18:20:09,683 unpacking tar archive at: mozilla-central-f8dd3f21e434/testing/mozharness/ program finished with exit code 0 elapsedTime=0.738119 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:20:10.190514) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-20 18:20:10.193864) ========= script_repo_revision: f8dd3f21e434be32fe5901849f1723b0e64cf668 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-20 18:20:10.194259) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-20 18:20:10.194534) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-20 18:20:10.208021) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-09-20 18:20:10.208408) ========= /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=9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:20:10 INFO - MultiFileLogger online at 20170920 18:20:10 in /builds/slave/test 18:20:10 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 18:20:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:20:10 INFO - {'append_to_log': False, 18:20:10 INFO - 'base_work_dir': '/builds/slave/test', 18:20:10 INFO - 'blob_upload_branch': 'Firefox', 18:20:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:20:10 INFO - 'branch': 'Firefox', 18:20:10 INFO - 'buildbot_json_path': 'buildprops.json', 18:20:10 INFO - 'code_coverage': False, 18:20:10 INFO - 'config_files': ('talos/linux_config.py',), 18:20:10 INFO - 'default_actions': ('clobber', 18:20:10 INFO - 'read-buildbot-config', 18:20:10 INFO - 'download-and-extract', 18:20:10 INFO - 'populate-webroot', 18:20:10 INFO - 'create-virtualenv', 18:20:10 INFO - 'install', 18:20:10 INFO - 'setup-mitmproxy', 18:20:10 INFO - 'run-tests'), 18:20:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:20:10 INFO - 'disable_ccov_upload': False, 18:20:10 INFO - 'disable_stylo': False, 18:20:10 INFO - 'download_minidump_stackwalk': True, 18:20:10 INFO - 'download_symbols': 'ondemand', 18:20:10 INFO - 'enable_stylo': False, 18:20:10 INFO - 'enable_webrender': False, 18:20:10 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:20:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:20:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:20:10 INFO - 'gecko_profile': False, 18:20:10 INFO - 'gecko_profile_interval': 0, 18:20:10 INFO - 'installer_path': 'installer.exe', 18:20:10 INFO - 'log_level': 'info', 18:20:10 INFO - 'log_name': 'talos', 18:20:10 INFO - 'log_to_console': True, 18:20:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:20:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:20:10 INFO - 'opt_config_files': (), 18:20:10 INFO - 'pip_index': False, 18:20:10 INFO - 'suite': 'dromaeojs-e10s', 18:20:10 INFO - 'system_bits': '32', 18:20:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:20:10 INFO - 'title': 'talos-linux64-ix-088', 18:20:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:20:10 INFO - 'use_talos_json': True, 18:20:10 INFO - 'verify': 'False', 18:20:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:20:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:20:10 INFO - 'work_dir': 'build'} 18:20:10 INFO - [mozharness: 2017-09-21 01:20:10.199809Z] Running clobber step. 18:20:10 INFO - Running pre-action listener: _resource_record_pre_action 18:20:10 INFO - Running main action method: clobber 18:20:10 INFO - rmtree: /builds/slave/test/build 18:20:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:20:11 INFO - Running post-action listener: _resource_record_post_action 18:20:11 INFO - [mozharness: 2017-09-21 01:20:11.190521Z] Finished clobber step (success) 18:20:11 INFO - [mozharness: 2017-09-21 01:20:11.190618Z] Running read-buildbot-config step. 18:20:11 INFO - Running pre-action listener: _resource_record_pre_action 18:20:11 INFO - Running main action method: read_buildbot_config 18:20:11 INFO - Using buildbot properties: 18:20:11 INFO - { 18:20:11 INFO - "project": "", 18:20:11 INFO - "product": "firefox", 18:20:11 INFO - "who": "kwierso@gmail.com", 18:20:11 INFO - "installer_path": "public/build/target.tar.bz2", 18:20:11 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 18:20:11 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:20:11 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 18:20:11 INFO - "stage_platform": "linux64-qr", 18:20:11 INFO - "basedir": "/builds/slave/test", 18:20:11 INFO - "buildnumber": 84, 18:20:11 INFO - "platform": "ubuntu64_hw_qr", 18:20:11 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:20:11 INFO - "slavebuilddir": "test", 18:20:11 INFO - "taskId": "aQu599aTT_uT5zX-i1i7WA", 18:20:11 INFO - "branch": "mozilla-central", 18:20:11 INFO - "script_repo_revision": "production", 18:20:11 INFO - "revision": "f8dd3f21e434be32fe5901849f1723b0e64cf668", 18:20:11 INFO - "slavename": "talos-linux64-ix-088", 18:20:11 INFO - "repo_path": "mozilla-central" 18:20:11 INFO - } 18:20:11 INFO - Finding installer, test and symbols from parent task. 18:20:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aQu599aTT_uT5zX-i1i7WA'}, attempt #1 18:20:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aQu599aTT_uT5zX-i1i7WA'}, attempt #1 18:20:12 INFO - Task dependencies: dyJtNxu0QmqzSHUsp_kA3w 18:20:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dyJtNxu0QmqzSHUsp_kA3w'}, attempt #1 18:20:12 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dyJtNxu0QmqzSHUsp_kA3w/artifacts/public/build/target.tar.bz2 18:20:12 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/dyJtNxu0QmqzSHUsp_kA3w/artifacts/public/build/target.test_packages.json 18:20:12 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dyJtNxu0QmqzSHUsp_kA3w/artifacts/public/build/target.crashreporter-symbols.zip 18:20:12 INFO - Running post-action listener: _resource_record_post_action 18:20:12 INFO - [mozharness: 2017-09-21 01:20:12.571618Z] Finished read-buildbot-config step (success) 18:20:12 INFO - [mozharness: 2017-09-21 01:20:12.571835Z] Running download-and-extract step. 18:20:12 INFO - Running pre-action listener: _resource_record_pre_action 18:20:12 INFO - Running main action method: download_and_extract 18:20:12 INFO - mkdir: /builds/slave/test/build/tests 18:20:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:12 INFO - trying https://queue.taskcluster.net/v1/task/dyJtNxu0QmqzSHUsp_kA3w/artifacts/public/build/target.test_packages.json 18:20:12 INFO - Downloading https://queue.taskcluster.net/v1/task/dyJtNxu0QmqzSHUsp_kA3w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:20:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dyJtNxu0QmqzSHUsp_kA3w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:20:13 INFO - Downloaded 989 bytes. 18:20:13 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:20:13 INFO - Using the following test package requirements: 18:20:13 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:20:13 INFO - u'common': [u'target.common.tests.zip'], 18:20:13 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:20:13 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:20:13 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:20:13 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:20:13 INFO - u'mozbase': [u'target.common.tests.zip'], 18:20:13 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:20:13 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:20:13 INFO - u'web-platform': [u'target.common.tests.zip', 18:20:13 INFO - u'target.web-platform.tests.tar.gz'], 18:20:13 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:20:13 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 18:20:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dyJtNxu0QmqzSHUsp_kA3w/artifacts/public/build/target.common.tests.zip 18:20:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dyJtNxu0QmqzSHUsp_kA3w/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:20:13 INFO - Fetch https://queue.taskcluster.net/v1/task/dyJtNxu0QmqzSHUsp_kA3w/artifacts/public/build/target.common.tests.zip into memory 18:20:14 INFO - Content-Length response header: 54733810 18:20:14 INFO - Bytes received: 54733810 18:20:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dyJtNxu0QmqzSHUsp_kA3w/artifacts/public/build/target.talos.tests.zip 18:20:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dyJtNxu0QmqzSHUsp_kA3w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:20:25 INFO - Fetch https://queue.taskcluster.net/v1/task/dyJtNxu0QmqzSHUsp_kA3w/artifacts/public/build/target.talos.tests.zip into memory 18:20:25 INFO - Content-Length response header: 14512509 18:20:25 INFO - Bytes received: 14512509 18:20:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:26 INFO - trying https://queue.taskcluster.net/v1/task/dyJtNxu0QmqzSHUsp_kA3w/artifacts/public/build/target.tar.bz2 18:20:26 INFO - Downloading https://queue.taskcluster.net/v1/task/dyJtNxu0QmqzSHUsp_kA3w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:20:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dyJtNxu0QmqzSHUsp_kA3w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:20:27 INFO - Downloaded 61530906 bytes. 18:20:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dyJtNxu0QmqzSHUsp_kA3w/artifacts/public/build/target.tar.bz2 18:20:27 INFO - mkdir: /builds/slave/test/properties 18:20:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:20:27 INFO - Writing to file /builds/slave/test/properties/build_url 18:20:27 INFO - Contents: 18:20:27 INFO - build_url:https://queue.taskcluster.net/v1/task/dyJtNxu0QmqzSHUsp_kA3w/artifacts/public/build/target.tar.bz2 18:20:27 INFO - Running post-action listener: _resource_record_post_action 18:20:27 INFO - Running post-action listener: find_tests_for_verification 18:20:27 INFO - Running post-action listener: set_extra_try_arguments 18:20:27 INFO - [mozharness: 2017-09-21 01:20:27.147542Z] Finished download-and-extract step (success) 18:20:27 INFO - [mozharness: 2017-09-21 01:20:27.147659Z] Running populate-webroot step. 18:20:27 INFO - Running pre-action listener: _resource_record_pre_action 18:20:27 INFO - Running main action method: populate_webroot 18:20:27 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:20:27 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:20:27 INFO - 'tests': ['tresize', 18:20:27 INFO - 'tcanvasmark']}, 18:20:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:20:27 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:20:27 INFO - 'tests': ['dromaeo_css', 18:20:27 INFO - 'kraken']}, 18:20:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:20:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:20:27 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:20:27 INFO - 'talos_options': ['--disable-stylo'], 18:20:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:20:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:20:27 INFO - 'tests': ['damp', 'tps']}, 18:20:27 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:20:27 INFO - 'talos_options': ['--disable-stylo'], 18:20:27 INFO - 'tests': ['damp', 'tps']}, 18:20:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:20:27 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:20:27 INFO - 'tests': ['dromaeo_dom']}, 18:20:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:20:27 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:20:27 INFO - 'tests': ['basic_compositor_video', 18:20:27 INFO - 'glvideo']}, 18:20:27 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:20:27 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:20:27 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:20:27 INFO - 'talos_options': ['--disable-stylo'], 18:20:27 INFO - 'tests': ['ts_paint_webext', 18:20:27 INFO - 'tp5o_webext']}, 18:20:27 INFO - 'other-e10s': {'tests': ['a11yr', 18:20:27 INFO - 'ts_paint', 18:20:27 INFO - 'tpaint', 18:20:27 INFO - 'sessionrestore', 18:20:27 INFO - 'sessionrestore_many_windows', 18:20:27 INFO - 'sessionrestore_no_auto_restore', 18:20:27 INFO - 'tabpaint']}, 18:20:27 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:20:27 INFO - 'tests': ['a11yr', 18:20:27 INFO - 'ts_paint', 18:20:27 INFO - 'tpaint', 18:20:27 INFO - 'sessionrestore', 18:20:27 INFO - 'sessionrestore_many_windows', 18:20:27 INFO - 'sessionrestore_no_auto_restore', 18:20:27 INFO - 'tabpaint']}, 18:20:27 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 18:20:27 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 18:20:27 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:20:27 INFO - 'tests': ['perf_reftest_singletons']}, 18:20:27 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:20:27 INFO - 'tests': ['bloom_basic']}, 18:20:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:20:27 INFO - 'tsvgr_opacity', 18:20:27 INFO - 'tart', 18:20:27 INFO - 'tscrollx', 18:20:27 INFO - 'tsvg_static']}, 18:20:27 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:20:27 INFO - 'tests': ['tsvgx', 18:20:27 INFO - 'tsvgr_opacity', 18:20:27 INFO - 'tart', 18:20:27 INFO - 'tscrollx', 18:20:27 INFO - 'tsvg_static']}, 18:20:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:20:27 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:20:27 INFO - 'talos_options': ['--disable-stylo'], 18:20:27 INFO - 'tests': ['tp5o']}, 18:20:27 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:20:27 INFO - 'talos_options': ['--mitmproxy', 18:20:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:20:27 INFO - '--firstNonBlankPaint'], 18:20:27 INFO - 'tests': ['tp6_google', 18:20:27 INFO - 'tp6_youtube', 18:20:27 INFO - 'tp6_amazon', 18:20:27 INFO - 'tp6_facebook']}, 18:20:27 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:20:27 INFO - 'talos_options': ['--disable-stylo', 18:20:27 INFO - '--mitmproxy', 18:20:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:20:27 INFO - '--firstNonBlankPaint'], 18:20:27 INFO - 'tests': ['tp6_google', 18:20:27 INFO - 'tp6_youtube', 18:20:27 INFO - 'tp6_amazon', 18:20:27 INFO - 'tp6_facebook']}, 18:20:27 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:20:27 INFO - 'talos_options': ['--stylo-threads=1', 18:20:27 INFO - '--mitmproxy', 18:20:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:20:27 INFO - '--firstNonBlankPaint'], 18:20:27 INFO - 'tests': ['tp6_google', 18:20:27 INFO - 'tp6_youtube', 18:20:27 INFO - 'tp6_amazon', 18:20:27 INFO - 'tp6_facebook']}, 18:20:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:20:27 INFO - 'talos_options': ['--xperf_path', 18:20:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:20:27 INFO - 'tests': ['tp5n']}, 18:20:27 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:20:27 INFO - 'talos_options': ['--disable-stylo', 18:20:27 INFO - '--xperf_path', 18:20:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:20:27 INFO - 'tests': ['tp5n']}}} 18:20:27 INFO - Running post-action listener: _resource_record_post_action 18:20:27 INFO - [mozharness: 2017-09-21 01:20:27.166660Z] Finished populate-webroot step (success) 18:20:27 INFO - [mozharness: 2017-09-21 01:20:27.166768Z] Running create-virtualenv step. 18:20:27 INFO - Running pre-action listener: _resource_record_pre_action 18:20:27 INFO - Running main action method: create_virtualenv 18:20:27 INFO - Creating virtualenv /builds/slave/test/build/venv 18:20:27 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:20:27 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:20:27 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:20:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:20:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:20:27 INFO - Installing distribute.............................................................................................................................................................................................done. 18:20:28 INFO - Installing pip...............done. 18:20:28 INFO - Return code: 0 18:20:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:20:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:20:28 INFO - Reading from file tmpfile_stdout 18:20:28 INFO - Output received: 18:20:28 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:20:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:20:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:28 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 0x14971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1660a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1803a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807090>, '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 0x1807d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1801f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', '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 18:20:28 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 18:20:28 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 18:20:28 INFO - Using env: {'DISPLAY': ':0', 18:20:28 INFO - 'HOME': '/home/cltbld', 18:20:28 INFO - 'LANG': 'en_US.UTF-8', 18:20:28 INFO - 'LANGUAGE': 'en_US:en', 18:20:28 INFO - 'LOGNAME': 'cltbld', 18:20:28 INFO - 'MAIL': '/var/mail/cltbld', 18:20:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:28 INFO - 'MOZ_NO_REMOTE': '1', 18:20:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:28 INFO - 'NO_EM_RESTART': '1', 18:20:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:28 INFO - 'PWD': '/builds/slave/test', 18:20:28 INFO - 'SHELL': '/bin/bash', 18:20:28 INFO - 'SHLVL': '1', 18:20:28 INFO - 'TERM': 'linux', 18:20:28 INFO - 'TMOUT': '86400', 18:20:28 INFO - 'USER': 'cltbld', 18:20:28 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', 18:20:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:28 INFO - '_': '/tools/buildbot/bin/python'} 18:20:29 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:20:29 INFO - Downloading/unpacking pip>=1.5 18:20:29 INFO - Running setup.py egg_info for package pip 18:20:29 INFO - warning: no files found matching 'pip/cacert.pem' 18:20:29 INFO - warning: no files found matching '*.html' under directory 'docs' 18:20:29 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:20:29 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:20:29 INFO - Installing collected packages: pip 18:20:29 INFO - Found existing installation: pip 1.1 18:20:29 INFO - Uninstalling pip: 18:20:29 INFO - Successfully uninstalled pip 18:20:29 INFO - Running setup.py install for pip 18:20:29 INFO - warning: no files found matching 'pip/cacert.pem' 18:20:29 INFO - warning: no files found matching '*.html' under directory 'docs' 18:20:29 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:20:29 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:20:29 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:20:29 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:20:29 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:20:29 INFO - Successfully installed pip 18:20:29 INFO - Cleaning up... 18:20:29 INFO - Return code: 0 18:20:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:20: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')]} 18:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20: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')]} 18:20:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:29 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 0x14971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1660a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1803a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807090>, '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 0x1807d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1801f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', '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 18:20:29 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 18:20:29 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 18:20:29 INFO - Using env: {'DISPLAY': ':0', 18:20:29 INFO - 'HOME': '/home/cltbld', 18:20:29 INFO - 'LANG': 'en_US.UTF-8', 18:20:29 INFO - 'LANGUAGE': 'en_US:en', 18:20:29 INFO - 'LOGNAME': 'cltbld', 18:20:29 INFO - 'MAIL': '/var/mail/cltbld', 18:20:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:29 INFO - 'MOZ_NO_REMOTE': '1', 18:20:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:29 INFO - 'NO_EM_RESTART': '1', 18:20:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:29 INFO - 'PWD': '/builds/slave/test', 18:20:29 INFO - 'SHELL': '/bin/bash', 18:20:29 INFO - 'SHLVL': '1', 18:20:29 INFO - 'TERM': 'linux', 18:20:29 INFO - 'TMOUT': '86400', 18:20:29 INFO - 'USER': 'cltbld', 18:20:29 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', 18:20:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:29 INFO - '_': '/tools/buildbot/bin/python'} 18:20:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:29 INFO - Downloading/unpacking psutil>=3.1.1 18:20:29 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 18:20:29 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 18:20:29 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 18:20:29 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 18:20:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:20:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:20:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:20:30 INFO - Installing collected packages: psutil 18:20:30 INFO - Running setup.py install for psutil 18:20:30 INFO - building 'psutil._psutil_linux' extension 18:20:30 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 18:20:31 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 18:20:31 INFO - building 'psutil._psutil_posix' extension 18:20:31 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 18:20:31 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 18:20:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:20:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:20:31 INFO - Successfully installed psutil 18:20:31 INFO - Cleaning up... 18:20:31 INFO - Return code: 0 18:20:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:20:31 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')]} 18:20:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:31 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')]} 18:20:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:31 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 0x14971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1660a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1803a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807090>, '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 0x1807d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1801f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', '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 18:20:31 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 18:20:31 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 18:20:31 INFO - Using env: {'DISPLAY': ':0', 18:20:31 INFO - 'HOME': '/home/cltbld', 18:20:31 INFO - 'LANG': 'en_US.UTF-8', 18:20:31 INFO - 'LANGUAGE': 'en_US:en', 18:20:31 INFO - 'LOGNAME': 'cltbld', 18:20:31 INFO - 'MAIL': '/var/mail/cltbld', 18:20:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:31 INFO - 'MOZ_NO_REMOTE': '1', 18:20:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:31 INFO - 'NO_EM_RESTART': '1', 18:20:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:31 INFO - 'PWD': '/builds/slave/test', 18:20:31 INFO - 'SHELL': '/bin/bash', 18:20:31 INFO - 'SHLVL': '1', 18:20:31 INFO - 'TERM': 'linux', 18:20:31 INFO - 'TMOUT': '86400', 18:20:31 INFO - 'USER': 'cltbld', 18:20:31 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', 18:20:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:31 INFO - '_': '/tools/buildbot/bin/python'} 18:20:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:20:31 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 18:20:31 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 18:20:31 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 18:20:31 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 18:20:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:20:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:20:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:20:32 INFO - Installing collected packages: mozsystemmonitor 18:20:32 INFO - Running setup.py install for mozsystemmonitor 18:20:32 INFO - Successfully installed mozsystemmonitor 18:20:32 INFO - Cleaning up... 18:20:33 INFO - Return code: 0 18:20:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:20:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x14971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1660a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1803a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807090>, '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 0x1807d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1801f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', '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 18:20:33 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 18:20:33 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 18:20:33 INFO - Using env: {'DISPLAY': ':0', 18:20:33 INFO - 'HOME': '/home/cltbld', 18:20:33 INFO - 'LANG': 'en_US.UTF-8', 18:20:33 INFO - 'LANGUAGE': 'en_US:en', 18:20:33 INFO - 'LOGNAME': 'cltbld', 18:20:33 INFO - 'MAIL': '/var/mail/cltbld', 18:20:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:33 INFO - 'MOZ_NO_REMOTE': '1', 18:20:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:33 INFO - 'NO_EM_RESTART': '1', 18:20:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:33 INFO - 'PWD': '/builds/slave/test', 18:20:33 INFO - 'SHELL': '/bin/bash', 18:20:33 INFO - 'SHLVL': '1', 18:20:33 INFO - 'TERM': 'linux', 18:20:33 INFO - 'TMOUT': '86400', 18:20:33 INFO - 'USER': 'cltbld', 18:20:33 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', 18:20:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:33 INFO - '_': '/tools/buildbot/bin/python'} 18:20:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:33 INFO - Downloading/unpacking jsonschema==2.5.1 18:20:33 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 18:20:33 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 18:20:33 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 18:20:33 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 18:20:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:20:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:20:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:35 INFO - Downloading functools32-3.2.3-2.tar.gz 18:20:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:20:35 INFO - warning: no files found matching '*.txt' 18:20:35 INFO - no previously-included directories found matching 'build' 18:20:35 INFO - no previously-included directories found matching 'dist' 18:20:35 INFO - no previously-included directories found matching '.git*' 18:20:35 INFO - Installing collected packages: jsonschema, functools32 18:20:35 INFO - Running setup.py install for functools32 18:20:36 INFO - warning: no files found matching '*.txt' 18:20:36 INFO - no previously-included directories found matching 'build' 18:20:36 INFO - no previously-included directories found matching 'dist' 18:20:36 INFO - no previously-included directories found matching '.git*' 18:20:36 INFO - Successfully installed jsonschema functools32 18:20:36 INFO - Cleaning up... 18:20:36 INFO - Return code: 0 18:20:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:20:36 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')]} 18:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:36 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')]} 18:20:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x14971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1660a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1803a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807090>, '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 0x1807d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1801f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', '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 18:20:36 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 18:20:36 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 18:20:36 INFO - Using env: {'DISPLAY': ':0', 18:20:36 INFO - 'HOME': '/home/cltbld', 18:20:36 INFO - 'LANG': 'en_US.UTF-8', 18:20:36 INFO - 'LANGUAGE': 'en_US:en', 18:20:36 INFO - 'LOGNAME': 'cltbld', 18:20:36 INFO - 'MAIL': '/var/mail/cltbld', 18:20:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:36 INFO - 'MOZ_NO_REMOTE': '1', 18:20:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:36 INFO - 'NO_EM_RESTART': '1', 18:20:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:36 INFO - 'PWD': '/builds/slave/test', 18:20:36 INFO - 'SHELL': '/bin/bash', 18:20:36 INFO - 'SHLVL': '1', 18:20:36 INFO - 'TERM': 'linux', 18:20:36 INFO - 'TMOUT': '86400', 18:20:36 INFO - 'USER': 'cltbld', 18:20:36 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', 18:20:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:36 INFO - '_': '/tools/buildbot/bin/python'} 18:20:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:20:36 INFO - Cleaning up... 18:20:36 INFO - Return code: 0 18:20:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:20:36 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')]} 18:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:36 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')]} 18:20:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x14971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1660a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1803a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807090>, '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 0x1807d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1801f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', '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 18:20:36 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 18:20:36 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 18:20:36 INFO - Using env: {'DISPLAY': ':0', 18:20:36 INFO - 'HOME': '/home/cltbld', 18:20:36 INFO - 'LANG': 'en_US.UTF-8', 18:20:36 INFO - 'LANGUAGE': 'en_US:en', 18:20:36 INFO - 'LOGNAME': 'cltbld', 18:20:36 INFO - 'MAIL': '/var/mail/cltbld', 18:20:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:36 INFO - 'MOZ_NO_REMOTE': '1', 18:20:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:36 INFO - 'NO_EM_RESTART': '1', 18:20:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:36 INFO - 'PWD': '/builds/slave/test', 18:20:36 INFO - 'SHELL': '/bin/bash', 18:20:36 INFO - 'SHLVL': '1', 18:20:36 INFO - 'TERM': 'linux', 18:20:36 INFO - 'TMOUT': '86400', 18:20:36 INFO - 'USER': 'cltbld', 18:20:36 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', 18:20:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:36 INFO - '_': '/tools/buildbot/bin/python'} 18:20:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:36 INFO - Downloading/unpacking blobuploader==1.2.4 18:20:36 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 18:20:36 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 18:20:36 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 18:20:36 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 18:20:37 INFO - Downloading blobuploader-1.2.4.tar.gz 18:20:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:20:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:20:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:20:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:20:38 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 18:20:38 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 18:20:38 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 18:20:38 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 18:20:38 INFO - Downloading docopt-0.6.1.tar.gz 18:20:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:20:38 INFO - Installing collected packages: blobuploader, requests, docopt 18:20:38 INFO - Running setup.py install for blobuploader 18:20:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:20:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:20:38 INFO - Running setup.py install for requests 18:20:38 INFO - Running setup.py install for docopt 18:20:38 INFO - Successfully installed blobuploader requests docopt 18:20:38 INFO - Cleaning up... 18:20:39 INFO - Return code: 0 18:20:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:20:39 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')]} 18:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:39 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')]} 18:20:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:39 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 0x14971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1660a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1803a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807090>, '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 0x1807d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1801f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', '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 18:20:39 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 18:20:39 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 18:20:39 INFO - Using env: {'DISPLAY': ':0', 18:20:39 INFO - 'HOME': '/home/cltbld', 18:20:39 INFO - 'LANG': 'en_US.UTF-8', 18:20:39 INFO - 'LANGUAGE': 'en_US:en', 18:20:39 INFO - 'LOGNAME': 'cltbld', 18:20:39 INFO - 'MAIL': '/var/mail/cltbld', 18:20:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:39 INFO - 'MOZ_NO_REMOTE': '1', 18:20:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:39 INFO - 'NO_EM_RESTART': '1', 18:20:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:39 INFO - 'PWD': '/builds/slave/test', 18:20:39 INFO - 'SHELL': '/bin/bash', 18:20:39 INFO - 'SHLVL': '1', 18:20:39 INFO - 'TERM': 'linux', 18:20:39 INFO - 'TMOUT': '86400', 18:20:39 INFO - 'USER': 'cltbld', 18:20:39 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', 18:20:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:39 INFO - '_': '/tools/buildbot/bin/python'} 18:20:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:20:39 INFO - Running setup.py (path:/tmp/pip-TQtrKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:20:39 INFO - Running setup.py (path:/tmp/pip-4ThToJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:20:39 INFO - Running setup.py (path:/tmp/pip-lkEixs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:20:39 INFO - Running setup.py (path:/tmp/pip-GQhzN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:20:39 INFO - Running setup.py (path:/tmp/pip-WpSsji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:20:39 INFO - Running setup.py (path:/tmp/pip-GQ2nTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:20:39 INFO - Running setup.py (path:/tmp/pip-ROTJrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:20:39 INFO - Running setup.py (path:/tmp/pip-eTaKSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:20:39 INFO - Running setup.py (path:/tmp/pip-Leg3v7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:20:39 INFO - Running setup.py (path:/tmp/pip-P2cNgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:20:39 INFO - Running setup.py (path:/tmp/pip-52ljZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:20:40 INFO - Running setup.py (path:/tmp/pip-77PRkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:20:40 INFO - Running setup.py (path:/tmp/pip-vCPtsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:20:40 INFO - Running setup.py (path:/tmp/pip-adVPbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:40 INFO - Running setup.py (path:/tmp/pip-pst6kU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:20:40 INFO - Running setup.py (path:/tmp/pip-rwP0Ii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:20:40 INFO - Running setup.py (path:/tmp/pip-i3EstF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:20:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:20:40 INFO - Running setup.py install for manifestparser 18:20:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:20:40 INFO - Running setup.py install for mozcrash 18:20:40 INFO - Running setup.py install for mozdebug 18:20:40 INFO - Running setup.py install for mozdevice 18:20:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:20:40 INFO - Running setup.py install for mozfile 18:20:40 INFO - Running setup.py install for mozhttpd 18:20:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:20:41 INFO - Running setup.py install for mozinfo 18:20:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:20:41 INFO - Running setup.py install for mozInstall 18:20:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:20:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:20:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:20:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:20:41 INFO - Running setup.py install for mozleak 18:20:41 INFO - Running setup.py install for mozlog 18:20:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:20:41 INFO - Running setup.py install for moznetwork 18:20:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:20:41 INFO - Running setup.py install for mozprocess 18:20:41 INFO - Running setup.py install for mozprofile 18:20:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:20:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:20:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:20:41 INFO - Running setup.py install for mozrunner 18:20:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:20:41 INFO - Running setup.py install for mozscreenshot 18:20:42 INFO - Running setup.py install for moztest 18:20:42 INFO - Running setup.py install for mozversion 18:20:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:20:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:20:42 INFO - Cleaning up... 18:20:42 INFO - Return code: 0 18:20:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:20:42 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')]} 18:20:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:42 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')]} 18:20:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:42 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 0x14971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1660a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1803a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807090>, '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 0x1807d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1801f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', '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 18:20:42 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 18:20:42 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 18:20:42 INFO - Using env: {'DISPLAY': ':0', 18:20:42 INFO - 'HOME': '/home/cltbld', 18:20:42 INFO - 'LANG': 'en_US.UTF-8', 18:20:42 INFO - 'LANGUAGE': 'en_US:en', 18:20:42 INFO - 'LOGNAME': 'cltbld', 18:20:42 INFO - 'MAIL': '/var/mail/cltbld', 18:20:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:42 INFO - 'MOZ_NO_REMOTE': '1', 18:20:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:42 INFO - 'NO_EM_RESTART': '1', 18:20:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:42 INFO - 'PWD': '/builds/slave/test', 18:20:42 INFO - 'SHELL': '/bin/bash', 18:20:42 INFO - 'SHLVL': '1', 18:20:42 INFO - 'TERM': 'linux', 18:20:42 INFO - 'TMOUT': '86400', 18:20:42 INFO - 'USER': 'cltbld', 18:20:42 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', 18:20:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:42 INFO - '_': '/tools/buildbot/bin/python'} 18:20:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:20:42 INFO - Running setup.py (path:/tmp/pip-Sjk134-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:20:42 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)) 18:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:20:42 INFO - Running setup.py (path:/tmp/pip-hydpUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:20:42 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)) 18:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:20:42 INFO - Running setup.py (path:/tmp/pip-pkre7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:20:42 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)) 18:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:20:42 INFO - Running setup.py (path:/tmp/pip-nCgO18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:20:42 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)) 18:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:20:42 INFO - Running setup.py (path:/tmp/pip-9Ke3vx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:20:42 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)) 18:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:20:42 INFO - Running setup.py (path:/tmp/pip-7aK1r4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:20:42 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)) 18:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:20:42 INFO - Running setup.py (path:/tmp/pip-YsKq2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:20:42 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)) 18:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:20:42 INFO - Running setup.py (path:/tmp/pip-Xk04jj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:20:43 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)) 18:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:20:43 INFO - Running setup.py (path:/tmp/pip-rNHnpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:20:43 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)) 18:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:20:43 INFO - Running setup.py (path:/tmp/pip-YnzhGY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:20:43 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)) 18:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:20:43 INFO - Running setup.py (path:/tmp/pip-4NdYTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:20:43 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)) 18:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:20:43 INFO - Running setup.py (path:/tmp/pip-BRJa0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:20:43 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)) 18:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:20:43 INFO - Running setup.py (path:/tmp/pip-ERP5Li-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:20:43 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)) 18:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:20:43 INFO - Running setup.py (path:/tmp/pip-Jo3paf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:20:43 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)) 18:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:43 INFO - Running setup.py (path:/tmp/pip-xxZUQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:43 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)) 18:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:20:43 INFO - Running setup.py (path:/tmp/pip-mS9R6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:20:43 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)) 18:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:20:43 INFO - Running setup.py (path:/tmp/pip-JBi7dG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:20:43 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)) 18:20:43 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)) 18:20:43 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)) 18:20:43 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)) 18:20:43 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)) 18:20:43 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)) 18:20:43 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)) 18:20:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:20:43 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 18:20:43 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 18:20:43 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 18:20:43 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 18:20:44 INFO - Downloading blessings-1.6.tar.gz 18:20:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:20:44 INFO - Installing collected packages: blessings 18:20:44 INFO - Running setup.py install for blessings 18:20:44 INFO - Successfully installed blessings 18:20:44 INFO - Cleaning up... 18:20:45 INFO - Return code: 0 18:20:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:20:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:20:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:20:45 INFO - Reading from file tmpfile_stdout 18:20:45 INFO - Current package versions: 18:20:45 INFO - argparse == 1.2.1 18:20:45 INFO - blessings == 1.6 18:20:45 INFO - blobuploader == 1.2.4 18:20:45 INFO - docopt == 0.6.1 18:20:45 INFO - functools32 == 3.2.3-2 18:20:45 INFO - jsonschema == 2.5.1 18:20:45 INFO - manifestparser == 1.1 18:20:45 INFO - mozInstall == 1.13 18:20:45 INFO - mozcrash == 1.0 18:20:45 INFO - mozdebug == 0.1 18:20:45 INFO - mozdevice == 0.51 18:20:45 INFO - mozfile == 1.2 18:20:45 INFO - mozhttpd == 0.7 18:20:45 INFO - mozinfo == 0.10 18:20:45 INFO - mozleak == 0.1 18:20:45 INFO - mozlog == 3.5 18:20:45 INFO - moznetwork == 0.27 18:20:45 INFO - mozprocess == 0.25 18:20:45 INFO - mozprofile == 0.28 18:20:45 INFO - mozrunner == 6.13 18:20:45 INFO - mozscreenshot == 0.1 18:20:45 INFO - mozsystemmonitor == 0.3 18:20:45 INFO - moztest == 0.8 18:20:45 INFO - mozversion == 1.4 18:20:45 INFO - psutil == 3.1.1 18:20:45 INFO - requests == 1.2.3 18:20:45 INFO - wsgiref == 0.1.2 18:20:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:20: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')]} 18:20:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20: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')]} 18:20:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:45 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 0x14971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1660a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1803a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807090>, '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 0x1807d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1801f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', '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 18:20:45 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 18:20:45 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 18:20:45 INFO - Using env: {'DISPLAY': ':0', 18:20:45 INFO - 'HOME': '/home/cltbld', 18:20:45 INFO - 'LANG': 'en_US.UTF-8', 18:20:45 INFO - 'LANGUAGE': 'en_US:en', 18:20:45 INFO - 'LOGNAME': 'cltbld', 18:20:45 INFO - 'MAIL': '/var/mail/cltbld', 18:20:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:45 INFO - 'MOZ_NO_REMOTE': '1', 18:20:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:45 INFO - 'NO_EM_RESTART': '1', 18:20:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:45 INFO - 'PWD': '/builds/slave/test', 18:20:45 INFO - 'SHELL': '/bin/bash', 18:20:45 INFO - 'SHLVL': '1', 18:20:45 INFO - 'TERM': 'linux', 18:20:45 INFO - 'TMOUT': '86400', 18:20:45 INFO - 'USER': 'cltbld', 18:20:45 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', 18:20:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:45 INFO - '_': '/tools/buildbot/bin/python'} 18:20:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:45 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)) 18:20:45 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)) 18:20:45 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)) 18:20:45 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)) 18:20:45 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)) 18:20:45 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)) 18:20:45 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)) 18:20:45 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)) 18:20:45 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)) 18:20:45 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:20:45 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 18:20:45 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 18:20:45 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 18:20:45 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 18:20:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:20:47 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)) 18:20:47 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)) 18:20:47 INFO - Installing collected packages: simplejson 18:20:47 INFO - Running setup.py install for simplejson 18:20:47 INFO - building 'simplejson._speedups' extension 18:20:47 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 18:20:48 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 18:20:48 INFO - Successfully installed simplejson 18:20:48 INFO - Cleaning up... 18:20:48 INFO - Return code: 0 18:20:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:20: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')]} 18:20:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20: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')]} 18:20:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1660a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1803a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807090>, '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 0x1807d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1801f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', '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 18:20: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', 'jsonschema'] in /builds/slave/test/build 18:20: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 jsonschema 18:20:48 INFO - Using env: {'DISPLAY': ':0', 18:20:48 INFO - 'HOME': '/home/cltbld', 18:20:48 INFO - 'LANG': 'en_US.UTF-8', 18:20:48 INFO - 'LANGUAGE': 'en_US:en', 18:20:48 INFO - 'LOGNAME': 'cltbld', 18:20:48 INFO - 'MAIL': '/var/mail/cltbld', 18:20:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:48 INFO - 'MOZ_NO_REMOTE': '1', 18:20:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:48 INFO - 'NO_EM_RESTART': '1', 18:20:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:48 INFO - 'PWD': '/builds/slave/test', 18:20:48 INFO - 'SHELL': '/bin/bash', 18:20:48 INFO - 'SHLVL': '1', 18:20:48 INFO - 'TERM': 'linux', 18:20:48 INFO - 'TMOUT': '86400', 18:20:48 INFO - 'USER': 'cltbld', 18:20:48 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', 18:20:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:48 INFO - '_': '/tools/buildbot/bin/python'} 18:20:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:20:48 INFO - Cleaning up... 18:20:48 INFO - Return code: 0 18:20:48 INFO - Running post-action listener: _resource_record_post_action 18:20:48 INFO - Running post-action listener: _start_resource_monitoring 18:20:48 INFO - Starting resource monitoring. 18:20:48 INFO - [mozharness: 2017-09-21 01:20:48.945707Z] Finished create-virtualenv step (success) 18:20:48 INFO - [mozharness: 2017-09-21 01:20:48.946231Z] Running install step. 18:20:48 INFO - Running pre-action listener: _resource_record_pre_action 18:20:48 INFO - Running main action method: install 18:20:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:20:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:20:49 INFO - Reading from file tmpfile_stdout 18:20:49 INFO - Detecting whether we're running mozinstall >=1.0... 18:20:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:20:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:20:49 INFO - Reading from file tmpfile_stdout 18:20:49 INFO - Output received: 18:20:49 INFO - Usage: mozinstall [options] installer 18:20:49 INFO - Options: 18:20:49 INFO - -h, --help show this help message and exit 18:20:49 INFO - -d DEST, --destination=DEST 18:20:49 INFO - Directory to install application into. [default: 18:20:49 INFO - "/builds/slave/test"] 18:20:49 INFO - --app=APP Application being installed. [default: firefox] 18:20:49 INFO - mkdir: /builds/slave/test/build/application 18:20:49 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'] 18:20:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:21:07 INFO - Reading from file tmpfile_stdout 18:21:07 INFO - Output received: 18:21:07 INFO - /builds/slave/test/build/application/firefox/firefox 18:21:07 INFO - Running post-action listener: _resource_record_post_action 18:21:07 INFO - [mozharness: 2017-09-21 01:21:07.299043Z] Finished install step (success) 18:21:07 INFO - [mozharness: 2017-09-21 01:21:07.299296Z] Running setup-mitmproxy step. 18:21:07 INFO - Running pre-action listener: _resource_record_pre_action 18:21:07 INFO - Running main action method: setup_mitmproxy 18:21:07 INFO - Skipping: mitmproxy is not required 18:21:07 INFO - Running post-action listener: _resource_record_post_action 18:21:07 INFO - [mozharness: 2017-09-21 01:21:07.300419Z] Finished setup-mitmproxy step (success) 18:21:07 INFO - [mozharness: 2017-09-21 01:21:07.300638Z] Running run-tests step. 18:21:07 INFO - Running pre-action listener: _resource_record_pre_action 18:21:07 INFO - Running pre-action listener: _set_gcov_prefix 18:21:07 INFO - Running main action method: run_tests 18:21:07 WARNING - Try message not found. 18:21:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:21:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:21:07 INFO - Python 2.7.3 18:21:07 INFO - Return code: 0 18:21:07 INFO - grabbing minidump binary from tooltool 18:21:07 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')]} 18:21:07 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 0x1807090>, '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 0x1807d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1801f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:21:07 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 18:21:07 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 18:21:07 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 18:21:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:21:07 INFO - Return code: 0 18:21:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:21:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:21:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:21:07 INFO - ENV: RUST_BACKTRACE is now full 18:21:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:21:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:21:07 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/dyJtNxu0QmqzSHUsp_kA3w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-088', '--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 18:21:07 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/dyJtNxu0QmqzSHUsp_kA3w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-088 --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 18:21:07 INFO - Using env: {'DISPLAY': ':0', 18:21:07 INFO - 'HOME': '/home/cltbld', 18:21:07 INFO - 'LANG': 'en_US.UTF-8', 18:21:07 INFO - 'LANGUAGE': 'en_US:en', 18:21:07 INFO - 'LOGNAME': 'cltbld', 18:21:07 INFO - 'MAIL': '/var/mail/cltbld', 18:21:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:21:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:21:07 INFO - 'MOZ_ACCELERATED': '1', 18:21:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:07 INFO - 'MOZ_NO_REMOTE': '1', 18:21:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:21:07 INFO - 'MOZ_WEBRENDER': '1', 18:21:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:07 INFO - 'NO_EM_RESTART': '1', 18:21:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:07 INFO - 'PWD': '/builds/slave/test', 18:21:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:21:07 INFO - 'RUST_BACKTRACE': 'full', 18:21:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:21:07 INFO - 'SHELL': '/bin/bash', 18:21:07 INFO - 'SHLVL': '1', 18:21:07 INFO - 'TERM': 'linux', 18:21:07 INFO - 'TMOUT': '86400', 18:21:07 INFO - 'USER': 'cltbld', 18:21:07 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', 18:21:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:07 INFO - '_': '/tools/buildbot/bin/python'} 18:21:07 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/dyJtNxu0QmqzSHUsp_kA3w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-088', '--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 18:21:07 INFO - mozversion application_buildid: 20170921003054 18:21:07 INFO - mozversion application_changeset: f8dd3f21e434be32fe5901849f1723b0e64cf668 18:21:07 INFO - mozversion application_display_name: Nightly 18:21:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:21:07 INFO - mozversion application_name: Firefox 18:21:07 INFO - mozversion application_remotingname: firefox 18:21:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:21:07 INFO - mozversion application_vendor: Mozilla 18:21:07 INFO - mozversion application_version: 57.0a1 18:21:07 INFO - mozversion platform_buildid: 20170921003054 18:21:07 INFO - mozversion platform_changeset: f8dd3f21e434be32fe5901849f1723b0e64cf668 18:21:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:21:07 INFO - mozversion platform_version: 57.0a1 18:21:07 INFO - using testdate: 1505956867 18:21:07 INFO - actual date: 1505956867 18:21:07 INFO - starting webserver on 'localhost:55999' 18:21:07 INFO - SUITE-START | Running 2 tests 18:21:07 INFO - TEST-START | dromaeo_css 18:21:07 INFO - Initialising browser for dromaeo_css test... 18:21:07 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55999/getInfo.html -profile /tmp/tmpcwMysL/profile 18:21:07 INFO - TEST-INFO | started process 22879 (/builds/slave/test/build/application/firefox/firefox http://localhost:55999/getInfo.html) 18:21:14 INFO - TEST-INFO | 22879: exit 0 18:21:14 INFO - Browser initialized. 18:21:14 INFO - Running cycle 1/1 for dromaeo_css test... 18:21:14 INFO - Using env: {'DISPLAY': ':0', 18:21:14 INFO - 'HOME': '/home/cltbld', 18:21:14 INFO - 'JSGC_DISABLE_POISONING': '1', 18:21:14 INFO - 'LANG': 'en_US.UTF-8', 18:21:14 INFO - 'LANGUAGE': 'en_US:en', 18:21:14 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:21:14 INFO - 'LOGNAME': 'cltbld', 18:21:14 INFO - 'MAIL': '/var/mail/cltbld', 18:21:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:21:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:21:14 INFO - 'MOZ_ACCELERATED': '1', 18:21:14 INFO - 'MOZ_CRASHREPORTER': '1', 18:21:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:14 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:21:14 INFO - 'MOZ_NO_REMOTE': '1', 18:21:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:21:14 INFO - 'MOZ_WEBRENDER': '1', 18:21:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:14 INFO - 'NO_EM_RESTART': '1', 18:21:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:14 INFO - 'PWD': '/builds/slave/test', 18:21:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:21:14 INFO - 'RUST_BACKTRACE': 'full', 18:21:14 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:21:14 INFO - 'SHELL': '/bin/bash', 18:21:14 INFO - 'SHLVL': '1', 18:21:14 INFO - 'TERM': 'linux', 18:21:14 INFO - 'TMOUT': '86400', 18:21:14 INFO - 'USER': 'cltbld', 18:21:14 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', 18:21:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:14 INFO - '_': '/tools/buildbot/bin/python'} 18:21:14 INFO - TEST-INFO | started process 23125 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcwMysL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:21:15 INFO - PID 23125 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:21:15 INFO - PID 23125 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:21:15 INFO - PID 23125 | 18:21:15 INFO - PID 23125 | (/builds/slave/test/build/application/firefox/firefox:23184): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:21:15 INFO - PID 23125 | 18:21:25 INFO - PID 23125 | RSS: Main: 184836096 18:21:25 INFO - PID 23125 | 18:24:23 INFO - PID 23125 | Cycle 1(1): loaded http://localhost:55999/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55999/tests/dromaeo/cssquery-ext.html) 18:24:24 INFO - PID 23125 | RSS: Main: 185872384 18:24:24 INFO - PID 23125 | 18:27:27 INFO - PID 23125 | Cycle 1(1): loaded http://localhost:55999/tests/dromaeo/cssquery-ext.html (next: http://localhost:55999/tests/dromaeo/cssquery-jquery.html) 18:27:27 INFO - PID 23125 | RSS: Main: 188346368 18:27:27 INFO - PID 23125 | 18:30:29 INFO - PID 23125 | Cycle 1(1): loaded http://localhost:55999/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55999/tests/dromaeo/cssquery-mootools.html) 18:30:30 INFO - PID 23125 | RSS: Main: 187125760 18:30:30 INFO - PID 23125 | 18:33:32 INFO - PID 23125 | Cycle 1(1): loaded http://localhost:55999/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55999/tests/dromaeo/cssquery-prototype.html) 18:33:33 INFO - PID 23125 | RSS: Main: 190443520 18:33:33 INFO - PID 23125 | 18:36:37 INFO - PID 23125 | Cycle 1(1): loaded http://localhost:55999/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55999/tests/dromaeo/cssquery-yui.html) 18:36:37 INFO - PID 23125 | RSS: Main: 186589184 18:36:37 INFO - PID 23125 | 18:39:39 INFO - PID 23125 | Cycle 1(1): loaded http://localhost:55999/tests/dromaeo/cssquery-yui.html (next: http://localhost:55999/tests/dromaeo/cssquery-dojo.html) 18:39:39 INFO - PID 23125 | RSS: Main: 191483904 18:39:39 INFO - PID 23125 | 18:39:39 INFO - PID 23125 | __start_tp_report 18:39:39 INFO - PID 23125 | _x_x_mozilla_page_load 18:39:39 INFO - PID 23125 | _x_x_mozilla_page_load_details 18:39:39 INFO - PID 23125 | |i|pagename|runs| 18:39:39 INFO - PID 23125 | |0;dojo.html;3104.2239440139965;3130.0766273949184;3136.2943337748993;3148.370325934812;3173.4922412414003;6153.53848461366;6229.688515574226;6260.060990851369;6310.463610593094;6349.079383489391;6230.875382492348;6247.812565623027;6266.1540692006565;6272.466840318567;6320.557560970722;5735.2544169257935;5749.568782341341;5783.884322313551;5790.30516338039;5792.623479473821;5851.678157701324;5871.677057761822;5894.17481552583;5895.145203945425;5958.910616340759;5827.941720582782;5844.576093991126;5909.11363295507;5915.290165180184;5923.348431672513;1229.7567292902988;5694.373618901917;5719.28508936383;5723.998300297431;5807.30312362515;5741.081426971665;5746.597738158331;5780.017397042513;5823.126531020339;5838.38696936824;6216.191895053659;6234;6265.592736472115;6322.968385158045;6384.48924086072;6694.364035416627;6702.932970670279;6725.2602213756445;6793.796186114425;6815.829604259884;6747.460203183734;6779.762708305186;6792.966035169842;6843.555168914005;6890.827729306758;6110.419510146529;6220.68896555175;6298.149749783766;6314.273858506284;6339.2392912850755;6768.289329620417;6917;6966.895496567556;7023.367896889305;7043.612601306977;6587.83530411734;6623.801285961429;6645;6686.632235227109;6706.329367063255;6657.068010478537;6664.233613134455;6742.797716068526;6793.830154246183;6817.284185160586;6705.295943925915;6709.463242940553;6761.256261811197;6766.086578311963;6814.557053791586;6455.193100862392;6586.440152586988;6614.0740296358545;6621.106150669694;6742.42689371409;5154.5103215194495;5171.3277273954145;5199.324087868628;5211.296474975906;5219.269302297681;4334.1981733379425;4554.180247555406;4651;4686.133065382915;4710.081534100886;5756.65460072397;5795.710214489259;5805.129230615357;5820.621659592113;5848.707564621752;6670.733170673216;6710.295418981034;6775.525713200029;6785.4732685145445;6854.177498700088;5603.971980140154;5643.774249030075;5709.74306156224;5737.3975732548315;5811.302643682785;5733.226014488074;5745;5794.82615521535;5799.217105690762;5811.27359080115;6447.677616119176;6449.451796597342;6459.289478157399;6471.9029214561815;6497;6754.5947243165565;6776.45788336922;6809.3871551559605;6814.1482314710665;6853.314668533306;6466.4503517201565;6550.737970481127;6552.475801935739;6566.409023187936;6568.1789776277965;6553.606783593;6642.202935647753;6648.800535983928;6692.83267918306;6746.325367463214;6577.50668699859;6620.735170593315;6709.329067093252;6711.697973591297;6794.422474089758;6350.777722779865;6417.871642567029;6429.58207716497;6449.161608990801;6457.580257283449;6400.55196136266;6424.614523128627;6514.511411644051;6532.248791388861;6600.636964966973;6109.816705498843;6159.599626024294;6426;6428.871422571616;6504.544681872224;6360.77737279193;6442.194725659293;6448.290688024407;6465.806025819233;6522.934770652233;6249.937500624936;6299.055141728777;6323.051542268512;6324.936750632435;6339.936600633934 18:39:39 INFO - PID 23125 | |1;ext.html;864.8789169516267;889.7686601483613;890.888638920135;894.253298495756;896.7892545251865;37440;40382;40656.796716016375;40860;40935.79532102339;36860.447093293624;37222.255554888885;37295.06762330943;37356.626433735655;37372.62627373725;7192.820179495502;7280.126384833828;7324.08448943882;7352.632368381586;7357.484976051678;7151.4993950423495;7213.675384607679;7238.276172382746;7250.492465527415;7305.342519173273;7223.747168849091;7279.381252593509;7280.8179795505275;7291.744788932362;7293.416526677879;7170.641467926583;7843.7254696085365;8002.119766825645;8005.439619226657;8009.519428834288;7817.570033648147;7886.408519361071;7886.64510097047;7895.368370530344;7907.604619769046;38245;38406.615933840585;38654.61345386566;39104.60895391038;39200.41199382012;3527;3602.7117830573493;3620.6922411594783;3622.5290712207507;3626.2928728897878;31015.84492077548;31229.687703122905;31333.216669583217;31360.686393136006;31569;5112.054269960069;5166.535011848951;5202.271681964528;5219.347581552306;5242.947570524284;188003;190845;191418.04290978456;191614;194110;41542.37686434706;42503.36244956328;46497;46917.29624055642;46940;49741.502584973685;50350;50496.990060198594;50800.491995079574;50893.74553127211;15876.761848572281;15900.681986360209;16100.355985760672;16112.597185070232;16114;12951.5466958656;13073.215607063607;13106.80339794904;13113.737725245443;13155.671108222372;13335.199888006751;13398;13449.125806822525;13463.932680336535;13586.320683965761;5347.545458635982;5468.535174510132;5480.862978425571;5508.366537848199;5527.613067085346;1003.6236411345745;1005.3967619428284;1008.1531513528671;1010.3331801011298;1030.0677886512717;22683.773162268168;23145.652815207784;23188.420289492897;23203.187888423825;23239.3028209154;1117.4468638024068;1125.9324440533594;1126.301692950376;1128.1482480727;1128.9322640641587;12177.452014659362;12186.08604354677;12222.083343749255;12245.510179592895;12434.75130497403;226622;227213;227570;229607;229901;51926.48073519292;52106;52235;52600.47399526032;52743.47256527462;1393.5053056165118;1465.9560213193622;1482.3922191901484;1483.9035462695106;1486.5094518808983;22263.55472890501;22669.319920402413;22795.202167924042;22812.543749124594;22992.655110173026;37245;37248.06879828026;37261.62738372581;37274.813625931696;37409.81295093616;193269;193462;194274;194458;194525;29590.852045739633;30031;31376;32575;33749.831250844574;42300;42431.7878410606;42768.572314277706;43222.78388608037;43384.566154339314;31716.36567268688;31722.68277317197;31945.36109277848;32060;32601.510977334885;31145.8442707785;31251.463153438643;31477.842610787717;31519;31626;35770.8211458941;35790.28419431649;35961.82019089888;36002;36116.09709757277;30290.394192115593;31050.534241985937;31613.683863161074;31653.683463165995;32055.198620034687;22857.88571057134;23375.649365259877;23484;23583.64624530599;23899.402514937265 18:39:39 INFO - PID 23125 | |2;jquery.html;588.7203578300305;589.313449830947;590.3093380744529;607.7174114036973;612.5497759147026;21772.673409898864;22376.32871013868;22571.54856902861;22678.659820102712;22739.20412785553;22509;23997.64003539944;24083.518329633396;24252.27243182703;24265.636015459782;6593.472522198224;6710.261871194165;6736.259011508755;6736.326367363249;6755.5271131020845;6626.834329141786;6693.531452798306;6695.464362850985;6695.799126026227;6727.461803055744;6309.3375195604485;6570.540062195648;6583.473322134218;6585.670716464181;6585.769498067569;6519.022146678012;6548.9017664735065;6567.54027218095;6572.112764776742;6590.5716128451495;5856.00447923854;6050.636961782263;6068.362821903681;6078.483328917047;6113.938860611426;27419.31451713723;27698.5845212322;27758.44483110347;28028.71971280302;28047;2016;2056.9177232910665;2061.2373421833868;2062.7730949595534;2064.64900966836;17997.460076197734;18496.352627658103;18566.16452259638;18660.440186794283;18847.71728424075;2259;2361.031976889467;2361.7756313150394;2369.9289021329387;2375.857448553075;123254.38372808168;128108;128835.35582322028;132468.01297980538;136578.63421365758;14483.927580362242;14718.264086795618;14739.115653060637;14940.947824786877;14963.027403218755;22595.661065084038;22799.544009120058;22934.19730309431;23024.53950920972;23055.88472057629;10021.899781002243;10027.548760305805;10081.899181008243;10333.431661258703;10411.219158562986;9519.143277105093;9519.286053546011;9536.141747242782;9573.569189386495;9640.662576809778;8804.559772011375;8887.178128483645;8998.415103018282;9012;9093.136152065543;2556.105363122892;2647.3454091193003;2728.126999360186;2735.097417852104;2741.2461573067567;940.8071345374182;959.2565761534867;968.3657204531044;975.6341371985505;975.697533764521;18704.158312875952;18961.905190474234;19253;19344.613107737765;19536.02319884;1010.9848352274722;1014.8985101489792;1029.3669393323203;1035.6271742172812;1046.26238501856;10639.574417023387;10655.987681170276;10701;10774.784504310026;10947.193713037419;463009;466408;470540;487185;489046;112069.43965280284;112902;114392;114669;114953;1138.5066703918392;1181.9704507387212;1240.3736113262926;1248.5193200617646;1259.7102666386781;19872.205111795367;20141.899290503454;20473.283435079702;20511.8974405127;20691.793082069587;57600.423995759505;58982.41017589769;60240.698796505734;60332.69833650804;60392.396076038676;73905.63047184945;75696.62151689206;76040;77437;77548;14245;14398.856011439751;14508;14509.274536272933;14515.346809393392;21641.675374869075;21729.13083476643;21827.12691492386;22063;22225.888870555544;16719.49841504757;17546.298148073933;17750;18011.279548818424;18135.637287253918;14351.282435878373;14988.175650339335;15098.094114353175;15871.127088010271;16575.336986520404;18410;18464.984425856706;18482;18578.721319179953;18740.531486712396;14267.286635668383;14365.497207597684;14438.927805361327;14466.782998255245;14486;17125.314987400365;17266.136693165543;17509.124543772505;17681.734773978143;17812.287508499514 18:39:39 INFO - PID 23125 | |3;mootools.html;1345.381124682646;1384.072671310222;1393.6655202751342;1401.8598140185982;1417.82986041675;15045.699086018287;15650.139242341667;15651.843481565194;15678.294476748546;15699.529014129594;15296.464623738171;15495.535133945972;15512.30194641241;15513.379464821423;15537.37850485982;2760.903368382107;2851.7576006039512;2866.956995645067;2876.856157192132;2896.1745902417815;2747.697753247141;2846.786491013172;2867.928301792451;2870.3111253299267;2876.5109931311727;2807.8736456859388;2842.2610121368384;2858.7141285871453;2875.7555607773365;2884.985575072132;2757.3382388226782;2763.599278104684;2782.526970415024;2784.8886044558194;2786.303424143964;2695.824771389873;2704.7971402144917;2706.323419145214;2715.7827373810246;2722.3194201449637;14584.562463126123;14739.852601474062;14739.92630036843;14996.250187490581;15060.849391506054;8037.477563958324;8298.917010829935;8307.958460207721;8363.20549547792;8366.874496882552;5404.810831620914;5621.718914054321;5629.690367029812;5985.610935289235;6035.124906888521;2021.8786872787532;2026.1996511377922;2029.6853987631794;2032.6646103392923;2071.3682326890294;24459.63310550343;25258.873705631355;27888;28006.719932800817;28740.712592874013;7494.437917156126;7651.808704782425;7667.654955527015;7800.375969922506;7806.102298235662;20306.593868122556;20470.692939605917;20478.795212047986;20491.897540512204;20500.89749551243;8350.373721970876;8470.915290847135;8582.356323275779;8715.738527844174;8724.738257852274;7569.507981981264;7664.73173438927;7673.923260767321;7808.5314881107315;7926.207379262028;5705.914411283834;5815.767369305265;5838.620489668158;5843.1235314703135;5961.910571341434;1742.364037126465;1751.0719318761078;1751.6934536456324;1767.169430367725;1799.9910000450177;909.0909090909091;919.6965001549606;928.7445952363154;934.7896723237217;934.9252059835197;7552.207018263113;7552.471327007167;7622;7670.309672129535;7687.615619219128;713.3900515059653;719.3777382564044;734.6987735028613;737.8782500887401;749.3181205103439;4533.274676051816;4598.287265473857;4648.744319062416;4691.132140554008;4700.106979673851;21395.572088558452;22669.773302266767;22682;22687.319380418612;22718.886405568195;21443.892780535996;22061.889690551765;22197.445063873532;22221.333359999226;22245.332640020824;9826.508674566243;10630.415327157081;11261.71845703864;11410.543578256942;11444.027257683023;8525.062243153125;8724.781880452785;8909.599068042084;8915.331350148635;8942.776430589027;18031.44283186857;18036.819631803515;18596.32633063031;18925.526861828563;18932.526686832938;18678.692491525464;19287.421377358703;19324;19701.014949252192;19814.90092549586;11179.66461006171;11616.186866919235;11766.882331176921;11786.762389949276;11816.113791465847;13350.732985340434;13487.32563371847;13563.86436135626;13571.1857288563;13620.387082581507;11078.335299882034;11350.375729334966;11416.543338266709;11472.082233421474;11526.94236528812;10662.8400573993;11067.169962252703;11180.832287515856;11247.18320767059;11432.199746017699;10086.89913100889;10088.69733907984;10140.391576505433;10144.391336519833;10147.949260253947;7071.646417679199;7100.218975912542;7114.715411383487;7118.679659415235;7319.865420859775;6646.335366463315;6859.622720750406;6909.550879192836;6959.060526828814;6998.860022799618 18:39:39 INFO - PID 23125 | |4;prototype.html;495.6183738521341;512.5335944290696;528.8069854503108;532.6404676843131;539.2558269587968;6108.908366374502;6158.353372895837;6177.691115444227;6217.347178546244;6258.593191442559;6083.300420451649;6093.817185484431;6137.202163718722;6139.171211886394;6162.22972128484;5070.974645126769;5100.2859599655985;5111.575244586118;5121.3342265505435;5123.948760512384;5090.567301779353;5116.334876466054;5134.435212126663;5145.3568303962;5186.1442861927735;4747.4540427850725;4852.199387101124;4893.339399181118;4915.139850526161;4946.406431228258;4888.926666100011;4899.265110233458;4910.680805747615;4961.776720047606;4971.6271279654175;4411.558844115595;4424.80088396023;4435.866923992286;4480.305552639346;4494.280915053609;5935.436133567304;5954;5961.403859614047;5966.731497082642;6017.849553761191;5560.193771903092;5599.692016939067;5625.46558076982;5671.943280567183;5682.346530149044;6272.372762723737;6324.588901721373;6355.3009168991375;6375.8087257381885;6415.230172379298;4476.641868650533;4713.858584242478;4772.832950846739;4777.116233496814;4819.373481447389;6466.861208826355;6482.805515834485;6584.374484423972;6652.201735791639;6679.33206679333;4867.537583929521;4929.605631549467;4936.407631084221;4947.367368768298;4948.727819969864;4958.752062396866;4970.478099799469;4998.300237966688;5025.271335656309;5028.566858445325;5462.781488740406;5510.393856675762;5540.786343920517;5544.805931792368;5599.272094627672;6336.841578960563;6357.014662727284;6372.5833041914;6409.391107844747;6484;1704.5397742609425;1709.9230534625972;1720.5977128640118;1737.9391721289694;1740.704080306351;1162.7790719763386;1212.7877621416217;1235.9814602780966;1243.657994051643;1252.3487786351047;5472.808451704172;5498.092814685612;5533.142362933751;5566.471185237396;5568.359638641608;6393.296737358887;6415.743370265231;6460.935390646127;6467.288598254188;6489.442533791856;5480.479354461319;5500.202470641814;5518.936989970605;5552.41699621542;5571.359293681197;3400.897973060812;3438.3639026780124;3450.861965521351;3462.7492130115625;3472.9652703472643;6256.280527739277;6268.49852011838;6284.591501552384;6307.842303942438;6335.796198722228;6611.338866113447;6681.832954176184;6703.463722902156;6720.193576770819;6729.629870357079;5883.588148829626;6071.726772295346;6236.6258024518675;6254.21822272216;6274.46667033289;6393.456556192589;6404.231492220963;6414.227195524066;6446.871062578816;6466.288708242184;6347.809565713036;6371.6814159290925;6424.421802037839;6456.192975878015;6465.288818230085;6163.691815409122;6188.350223226496;6189.7833575826435;6242.843928901813;6273.341299163704;4796.56830885222;4824.6863953842885;4923.679960802537;4942.75286235694;4952.761809547613;6300.432961033529;6466.321036291121;6477.838054048498;6516.967415162894;6545.247296560957;6225.533085018732;6382.5532212744665;6391.712372943136;6396.552241343062;6415.390537898892;6334.954732469091;6350.333215012357;6425.1968503937005;6430.2926678066315;6435.967820160869;6189.226346706661;6228.377162283736;6311.936880631135;6332.366763323631;6345.111684364285;6247.156633854372;6288.937110629018;6350.936490635219;6441.645709486023;6518.43557546195;6159.969200153971;6166.784162554289;6193.049189505291;6197.845053873689;6289.308176100717 18:39:39 INFO - PID 23125 | |5;yui.html;889.2174886100231;923.7967647117633;930.3115694386156;934.8737920380746;949.9002604726503;19122.713159302602;19786.802131978675;19787.406377808653;19864.801351986476;19892.40322790315;15779.1321477319;15903.045817250973;15924.840751592481;15984;16312.592185195375;947.5877993073001;965.5268918230087;972.2999440402899;976.0483528559633;977.6480467031863;971.4608392342247;976.3360914578084;976.3556053005018;981.4160574458186;981.6318880419842;981.4454833019357;1007.7934023525196;1008.8234558952154;1013.3058854684565;1014.5028935821468;1003.9799204015916;1004.693568461619;1005.215931573374;1009.4599389326748;1012.7265638277661;717.9353858152791;740.2597402597403;746.6490749347798;749.9887501687424;751;19405;19429.80570194294;19519.80480195194;19572.31496897616;19617.803821961883;21858.344249672537;21957;22400.21599244035;22490.887545562167;22751.317460476213;17249.22378492971;17597.648047039114;17746.467605971713;17792.3772667956;17932.641347173114;3637.7635453695425;3649.6167902370134;3650.1969566695284;3653.1415117447377;3659.396199627058;138959;141436.99999999898;142974;143552.28223858814;143892;15176.848231517764;15319.693606127816;15348.693026139637;15671.608209794618;15692.92153539225;31223.219419514695;31262;31378;31975.840120799712;32332;8551.914480855236;8599.14008599135;8678.913210867811;8699.260562852229;8702.260307873901;4324.113556720865;4350.6736994725525;4376.059147283349;4457;4460.085682435093;5273.894522109536;5349.411564727877;5384.19237114436;5421;5438.320209973754;948.1135138645251;963.8843338799248;982.8771403574553;984.778424854402;985.763416779982;690.4855882367668;696.3036963036963;710.0343532795395;717.9928200718031;718.1705130574195;11457.484413201259;11583.247088939192;11586.826197607043;12990.415431305613;13411.731765364639;708.7058870568657;709.6558169287891;719.8272414620453;725.3399406540024;728.2608152724988;5594.860128496739;5613.831585052455;5627.296587926509;5645.491905728504;5700.572457065654;117519.41240293744;121575.39212303882;121593;121618;122238;100475.0795545947;100718.4964075175;104659;112160;112196.43901780438;82617.58691206506;85899.5705021471;87389.99999999873;88006;90185.54907225553;12887.29119898377;13235.80146297787;13384.531541396005;13394.397252123483;13624.795628065389;41215;43106.13787724291;43969;44053.77973110114;44509.33236001527;55921.44078559162;56725.71637141953;56794.43205567892;56904.71547642235;58111.418885812294;17945;18197.54506137357;18280.177392017657;18360;18444.81555184431;21244.468888277916;21474.14103435845;21551.137954481645;21561.24535641245;21568.137274508845;19726.704099438808;19871.801281986995;19890.204391824165;19929.800701992794;20127.899360503103;18140.546486337946;18328.17523211432;18589.9070504652;18625.53436164107;18720;18510.352137675116;18970.24119035223;19240.615187696447;19402.514937126685;19739.506512337306;13883.514077007256;13900.582982510541;13972.301384930915;14336.139831610137;14435.566932992027;13955.720885582434;14062.718745625234;14144.929275353557;14145.575632731034;14275.500357487437 18:39:39 INFO - PID 23125 | __end_tp_report 18:39:39 INFO - PID 23125 | __start_cc_report 18:39:39 INFO - PID 23125 | _x_x_mozilla_cycle_collect,281 18:39:39 INFO - PID 23125 | __end_cc_report 18:39:39 INFO - PID 23125 | __startTimestamp1505957979947__endTimestamp 18:39:39 INFO - PID 23125 | 18:39:39 INFO - PID 23125 | ------- Summary: start ------- 18:39:39 INFO - PID 23125 | Number of tests: 6 18:39:39 INFO - PID 23125 | 18:39:39 INFO - PID 23125 | [#0] dojo.html Cycles:165 Average:6138.88 Median:6360.78 stddev:836.92 (13.2%) stddev-sans-first:804.92 18:39:39 INFO - PID 23125 | Values: 3104.2 3130.1 3136.3 3148.4 3173.5 6153.5 6229.7 6260.1 6310.5 6349.1 6230.9 6247.8 6266.2 6272.5 6320.6 5735.3 5749.6 5783.9 5790.3 5792.6 5851.7 5871.7 5894.2 5895.1 5958.9 5827.9 5844.6 5909.1 5915.3 5923.3 1229.8 5694.4 5719.3 5724.0 5807.3 5741.1 5746.6 5780.0 5823.1 5838.4 6216.2 6234.0 6265.6 6323.0 6384.5 6694.4 6702.9 6725.3 6793.8 6815.8 6747.5 6779.8 6793.0 6843.6 6890.8 6110.4 6220.7 6298.1 6314.3 6339.2 6768.3 6917.0 6966.9 7023.4 7043.6 6587.8 6623.8 6645.0 6686.6 6706.3 6657.1 6664.2 6742.8 6793.8 6817.3 6705.3 6709.5 6761.3 6766.1 6814.6 6455.2 6586.4 6614.1 6621.1 6742.4 5154.5 5171.3 5199.3 5211.3 5219.3 4334.2 4554.2 4651.0 4686.1 4710.1 5756.7 5795.7 5805.1 5820.6 5848.7 6670.7 6710.3 6775.5 6785.5 6854.2 5604.0 5643.8 5709.7 5737.4 5811.3 5733.2 5745.0 5794.8 5799.2 5811.3 6447.7 6449.5 6459.3 6471.9 6497.0 6754.6 6776.5 6809.4 6814.1 6853.3 6466.5 6550.7 6552.5 6566.4 6568.2 6553.6 6642.2 6648.8 6692.8 6746.3 6577.5 6620.7 6709.3 6711.7 6794.4 6350.8 6417.9 6429.6 6449.2 6457.6 6400.6 6424.6 6514.5 6532.2 6600.6 6109.8 6159.6 6426.0 6428.9 6504.5 6360.8 6442.2 6448.3 6465.8 6522.9 6249.9 6299.1 6323.1 6324.9 6339.9 18:39:39 INFO - PID 23125 | 18:39:39 INFO - PID 23125 | [#1] ext.html Cycles:180 Average:36968.30 Median:19648.91 stddev:53129.51 (270.4%) stddev-sans-first:53209.39 18:39:39 INFO - PID 23125 | Values: 864.9 889.8 890.9 894.3 896.8 37440.0 40382.0 40656.8 40860.0 40935.8 36860.4 37222.3 37295.1 37356.6 37372.6 7192.8 7280.1 7324.1 7352.6 7357.5 7151.5 7213.7 7238.3 7250.5 7305.3 7223.7 7279.4 7280.8 7291.7 7293.4 7170.6 7843.7 8002.1 8005.4 8009.5 7817.6 7886.4 7886.6 7895.4 7907.6 38245.0 38406.6 38654.6 39104.6 39200.4 3527.0 3602.7 3620.7 3622.5 3626.3 31015.8 31229.7 31333.2 31360.7 31569.0 5112.1 5166.5 5202.3 5219.3 5242.9 188003.0 190845.0 191418.0 191614.0 194110.0 41542.4 42503.4 46497.0 46917.3 46940.0 49741.5 50350.0 50497.0 50800.5 50893.7 15876.8 15900.7 16100.4 16112.6 16114.0 12951.5 13073.2 13106.8 13113.7 13155.7 13335.2 13398.0 13449.1 13463.9 13586.3 5347.5 5468.5 5480.9 5508.4 5527.6 1003.6 1005.4 1008.2 1010.3 1030.1 22683.8 23145.7 23188.4 23203.2 23239.3 1117.4 1125.9 1126.3 1128.1 1128.9 12177.5 12186.1 12222.1 12245.5 12434.8 226622.0 227213.0 227570.0 229607.0 229901.0 51926.5 52106.0 52235.0 52600.5 52743.5 1393.5 1466.0 1482.4 1483.9 1486.5 22263.6 22669.3 22795.2 22812.5 22992.7 37245.0 37248.1 37261.6 37274.8 37409.8 193269.0 193462.0 194274.0 194458.0 194525.0 29590.9 30031.0 31376.0 32575.0 33749.8 42300.0 42431.8 42768.6 43222.8 43384.6 31716.4 31722.7 31945.4 32060.0 32601.5 31145.8 31251.5 31477.8 31519.0 31626.0 35770.8 35790.3 35961.8 36002.0 36116.1 30290.4 31050.5 31613.7 31653.7 32055.2 22857.9 23375.6 23484.0 23583.6 23899.4 18:39:39 INFO - PID 23125 | 18:39:39 INFO - PID 23125 | [#2] jquery.html Cycles:180 Average:34078.18 Median:22301.11 stddev:80110.91 (359.2%) stddev-sans-first:80296.18 18:39:39 INFO - PID 23125 | Values: 588.7 589.3 590.3 607.7 612.5 21772.7 22376.3 22571.5 22678.7 22739.2 22509.0 23997.6 24083.5 24252.3 24265.6 6593.5 6710.3 6736.3 6736.3 6755.5 6626.8 6693.5 6695.5 6695.8 6727.5 6309.3 6570.5 6583.5 6585.7 6585.8 6519.0 6548.9 6567.5 6572.1 6590.6 5856.0 6050.6 6068.4 6078.5 6113.9 27419.3 27698.6 27758.4 28028.7 28047.0 2016.0 2056.9 2061.2 2062.8 2064.6 17997.5 18496.4 18566.2 18660.4 18847.7 2259.0 2361.0 2361.8 2369.9 2375.9 123254.4 128108.0 128835.4 132468.0 136578.6 14483.9 14718.3 14739.1 14940.9 14963.0 22595.7 22799.5 22934.2 23024.5 23055.9 10021.9 10027.5 10081.9 10333.4 10411.2 9519.1 9519.3 9536.1 9573.6 9640.7 8804.6 8887.2 8998.4 9012.0 9093.1 2556.1 2647.3 2728.1 2735.1 2741.2 940.8 959.3 968.4 975.6 975.7 18704.2 18961.9 19253.0 19344.6 19536.0 1011.0 1014.9 1029.4 1035.6 1046.3 10639.6 10656.0 10701.0 10774.8 10947.2 463009.0 466408.0 470540.0 487185.0 489046.0 112069.4 112902.0 114392.0 114669.0 114953.0 1138.5 1182.0 1240.4 1248.5 1259.7 19872.2 20141.9 20473.3 20511.9 20691.8 57600.4 58982.4 60240.7 60332.7 60392.4 73905.6 75696.6 76040.0 77437.0 77548.0 14245.0 14398.9 14508.0 14509.3 14515.3 21641.7 21729.1 21827.1 22063.0 22225.9 16719.5 17546.3 17750.0 18011.3 18135.6 14351.3 14988.2 15098.1 15871.1 16575.3 18410.0 18465.0 18482.0 18578.7 18740.5 14267.3 14365.5 14438.9 14466.8 14486.0 17125.3 17266.1 17509.1 17681.7 17812.3 18:39:39 INFO - PID 23125 | 18:39:39 INFO - PID 23125 | [#3] mootools.html Cycles:180 Average:9553.88 Median:2735.01 stddev:6872.12 (251.3%) stddev-sans-first:6863.72 18:39:39 INFO - PID 23125 | Values: 1345.4 1384.1 1393.7 1401.9 1417.8 15045.7 15650.1 15651.8 15678.3 15699.5 15296.5 15495.5 15512.3 15513.4 15537.4 2760.9 2851.8 2867.0 2876.9 2896.2 2747.7 2846.8 2867.9 2870.3 2876.5 2807.9 2842.3 2858.7 2875.8 2885.0 2757.3 2763.6 2782.5 2784.9 2786.3 2695.8 2704.8 2706.3 2715.8 2722.3 14584.6 14739.9 14739.9 14996.3 15060.8 8037.5 8298.9 8308.0 8363.2 8366.9 5404.8 5621.7 5629.7 5985.6 6035.1 2021.9 2026.2 2029.7 2032.7 2071.4 24459.6 25258.9 27888.0 28006.7 28740.7 7494.4 7651.8 7667.7 7800.4 7806.1 20306.6 20470.7 20478.8 20491.9 20500.9 8350.4 8470.9 8582.4 8715.7 8724.7 7569.5 7664.7 7673.9 7808.5 7926.2 5705.9 5815.8 5838.6 5843.1 5961.9 1742.4 1751.1 1751.7 1767.2 1800.0 909.1 919.7 928.7 934.8 934.9 7552.2 7552.5 7622.0 7670.3 7687.6 713.4 719.4 734.7 737.9 749.3 4533.3 4598.3 4648.7 4691.1 4700.1 21395.6 22669.8 22682.0 22687.3 22718.9 21443.9 22061.9 22197.4 22221.3 22245.3 9826.5 10630.4 11261.7 11410.5 11444.0 8525.1 8724.8 8909.6 8915.3 8942.8 18031.4 18036.8 18596.3 18925.5 18932.5 18678.7 19287.4 19324.0 19701.0 19814.9 11179.7 11616.2 11766.9 11786.8 11816.1 13350.7 13487.3 13563.9 13571.2 13620.4 11078.3 11350.4 11416.5 11472.1 11526.9 10662.8 11067.2 11180.8 11247.2 11432.2 10086.9 10088.7 10140.4 10144.4 10147.9 7071.6 7100.2 7114.7 7118.7 7319.9 6646.3 6859.6 6909.6 6959.1 6998.9 18:39:39 INFO - PID 23125 | 18:39:39 INFO - PID 23125 | [#4] prototype.html Cycles:180 Average:5372.52 Median:6044.79 stddev:1480.19 (24.5%) stddev-sans-first:1438.37 18:39:39 INFO - PID 23125 | Values: 495.6 512.5 528.8 532.6 539.3 6108.9 6158.4 6177.7 6217.3 6258.6 6083.3 6093.8 6137.2 6139.2 6162.2 5071.0 5100.3 5111.6 5121.3 5123.9 5090.6 5116.3 5134.4 5145.4 5186.1 4747.5 4852.2 4893.3 4915.1 4946.4 4888.9 4899.3 4910.7 4961.8 4971.6 4411.6 4424.8 4435.9 4480.3 4494.3 5935.4 5954.0 5961.4 5966.7 6017.8 5560.2 5599.7 5625.5 5671.9 5682.3 6272.4 6324.6 6355.3 6375.8 6415.2 4476.6 4713.9 4772.8 4777.1 4819.4 6466.9 6482.8 6584.4 6652.2 6679.3 4867.5 4929.6 4936.4 4947.4 4948.7 4958.8 4970.5 4998.3 5025.3 5028.6 5462.8 5510.4 5540.8 5544.8 5599.3 6336.8 6357.0 6372.6 6409.4 6484.0 1704.5 1709.9 1720.6 1737.9 1740.7 1162.8 1212.8 1236.0 1243.7 1252.3 5472.8 5498.1 5533.1 5566.5 5568.4 6393.3 6415.7 6460.9 6467.3 6489.4 5480.5 5500.2 5518.9 5552.4 5571.4 3400.9 3438.4 3450.9 3462.7 3473.0 6256.3 6268.5 6284.6 6307.8 6335.8 6611.3 6681.8 6703.5 6720.2 6729.6 5883.6 6071.7 6236.6 6254.2 6274.5 6393.5 6404.2 6414.2 6446.9 6466.3 6347.8 6371.7 6424.4 6456.2 6465.3 6163.7 6188.4 6189.8 6242.8 6273.3 4796.6 4824.7 4923.7 4942.8 4952.8 6300.4 6466.3 6477.8 6517.0 6545.2 6225.5 6382.6 6391.7 6396.6 6415.4 6335.0 6350.3 6425.2 6430.3 6436.0 6189.2 6228.4 6311.9 6332.4 6345.1 6247.2 6288.9 6350.9 6441.6 6518.4 6160.0 6166.8 6193.0 6197.8 6289.3 18:39:39 INFO - PID 23125 | 18:39:39 INFO - PID 23125 | [#5] yui.html Cycles:180 Average:24587.54 Median:21512.64 stddev:34608.83 (160.9%) stddev-sans-first:34660.17 18:39:39 INFO - PID 23125 | Values: 889.2 923.8 930.3 934.9 949.9 19122.7 19786.8 19787.4 19864.8 19892.4 15779.1 15903.0 15924.8 15984.0 16312.6 947.6 965.5 972.3 976.0 977.6 971.5 976.3 976.4 981.4 981.6 981.4 1007.8 1008.8 1013.3 1014.5 1004.0 1004.7 1005.2 1009.5 1012.7 717.9 740.3 746.6 750.0 751.0 19405.0 19429.8 19519.8 19572.3 19617.8 21858.3 21957.0 22400.2 22490.9 22751.3 17249.2 17597.6 17746.5 17792.4 17932.6 3637.8 3649.6 3650.2 3653.1 3659.4 138959.0 141437.0 142974.0 143552.3 143892.0 15176.8 15319.7 15348.7 15671.6 15692.9 31223.2 31262.0 31378.0 31975.8 32332.0 8551.9 8599.1 8678.9 8699.3 8702.3 4324.1 4350.7 4376.1 4457.0 4460.1 5273.9 5349.4 5384.2 5421.0 5438.3 948.1 963.9 982.9 984.8 985.8 690.5 696.3 710.0 718.0 718.2 11457.5 11583.2 11586.8 12990.4 13411.7 708.7 709.7 719.8 725.3 728.3 5594.9 5613.8 5627.3 5645.5 5700.6 117519.4 121575.4 121593.0 121618.0 122238.0 100475.1 100718.5 104659.0 112160.0 112196.4 82617.6 85899.6 87390.0 88006.0 90185.5 12887.3 13235.8 13384.5 13394.4 13624.8 41215.0 43106.1 43969.0 44053.8 44509.3 55921.4 56725.7 56794.4 56904.7 58111.4 17945.0 18197.5 18280.2 18360.0 18444.8 21244.5 21474.1 21551.1 21561.2 21568.1 19726.7 19871.8 19890.2 19929.8 20127.9 18140.5 18328.2 18589.9 18625.5 18720.0 18510.4 18970.2 19240.6 19402.5 19739.5 13883.5 13900.6 13972.3 14336.1 14435.6 13955.7 14062.7 14144.9 14145.6 14275.5 18:39:39 INFO - PID 23125 | -------- Summary: end -------- 18:39:39 INFO - PID 23125 | 18:39:40 INFO - TEST-INFO | 23125: exit 0 18:39:40 INFO - TEST-OK | dromaeo_css | took 1113210ms 18:39:40 INFO - TEST-START | kraken 18:39:40 INFO - Initialising browser for kraken test... 18:39:40 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55999/getInfo.html -profile /tmp/tmpeSiwln/profile 18:39:40 INFO - TEST-INFO | started process 23504 (/builds/slave/test/build/application/firefox/firefox http://localhost:55999/getInfo.html) 18:39:46 INFO - TEST-INFO | 23504: exit 0 18:39:46 INFO - Browser initialized. 18:39:46 INFO - Running cycle 1/1 for kraken test... 18:39:46 INFO - Using env: {'DISPLAY': ':0', 18:39:46 INFO - 'HOME': '/home/cltbld', 18:39:46 INFO - 'JSGC_DISABLE_POISONING': '1', 18:39:46 INFO - 'LANG': 'en_US.UTF-8', 18:39:46 INFO - 'LANGUAGE': 'en_US:en', 18:39:46 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:39:46 INFO - 'LOGNAME': 'cltbld', 18:39:46 INFO - 'MAIL': '/var/mail/cltbld', 18:39:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:39:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:39:46 INFO - 'MOZ_ACCELERATED': '1', 18:39:46 INFO - 'MOZ_CRASHREPORTER': '1', 18:39:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:46 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:39:46 INFO - 'MOZ_NO_REMOTE': '1', 18:39:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:39:46 INFO - 'MOZ_WEBRENDER': '1', 18:39:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:39:46 INFO - 'NO_EM_RESTART': '1', 18:39:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:39:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:46 INFO - 'PWD': '/builds/slave/test', 18:39:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:39:46 INFO - 'RUST_BACKTRACE': 'full', 18:39:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:39:46 INFO - 'SHELL': '/bin/bash', 18:39:46 INFO - 'SHLVL': '1', 18:39:46 INFO - 'TERM': 'linux', 18:39:46 INFO - 'TMOUT': '86400', 18:39:46 INFO - 'USER': 'cltbld', 18:39:46 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035', 18:39:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:46 INFO - '_': '/tools/buildbot/bin/python'} 18:39:46 INFO - TEST-INFO | started process 23750 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeSiwln/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:39:47 INFO - PID 23750 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:39:47 INFO - PID 23750 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:39:47 INFO - PID 23750 | 18:39:47 INFO - PID 23750 | (/builds/slave/test/build/application/firefox/firefox:23809): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:39:47 INFO - PID 23750 | 18:39:58 INFO - PID 23750 | RSS: Main: 185167872 18:39:58 INFO - PID 23750 | 18:40:00 INFO - PID 23750 | Cycle 1(1): loaded http://localhost:55999/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55999/tests/kraken/driver.html?testName=audio-beat-detection) 18:40:01 INFO - PID 23750 | RSS: Main: 183820288 18:40:01 INFO - PID 23750 | 18:40:04 INFO - PID 23750 | Cycle 1(1): loaded http://localhost:55999/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55999/tests/kraken/driver.html?testName=audio-dft) 18:40:05 INFO - PID 23750 | RSS: Main: 183767040 18:40:05 INFO - PID 23750 | 18:40:08 INFO - PID 23750 | Cycle 1(1): loaded http://localhost:55999/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55999/tests/kraken/driver.html?testName=audio-fft) 18:40:08 INFO - PID 23750 | RSS: Main: 180625408 18:40:08 INFO - PID 23750 | 18:40:10 INFO - PID 23750 | Cycle 1(1): loaded http://localhost:55999/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55999/tests/kraken/driver.html?testName=audio-oscillator) 18:40:11 INFO - PID 23750 | RSS: Main: 181096448 18:40:11 INFO - PID 23750 | 18:40:13 INFO - PID 23750 | Cycle 1(1): loaded http://localhost:55999/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55999/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:40:14 INFO - PID 23750 | RSS: Main: 181911552 18:40:14 INFO - PID 23750 | 18:40:17 INFO - PID 23750 | Cycle 1(1): loaded http://localhost:55999/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55999/tests/kraken/driver.html?testName=imaging-darkroom) 18:40:18 INFO - PID 23750 | RSS: Main: 183848960 18:40:18 INFO - PID 23750 | 18:40:21 INFO - PID 23750 | Cycle 1(1): loaded http://localhost:55999/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55999/tests/kraken/driver.html?testName=imaging-desaturate) 18:40:22 INFO - PID 23750 | RSS: Main: 183574528 18:40:22 INFO - PID 23750 | 18:40:25 INFO - PID 23750 | Cycle 1(1): loaded http://localhost:55999/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55999/tests/kraken/driver.html?testName=json-parse-financial) 18:40:26 INFO - PID 23750 | RSS: Main: 184385536 18:40:26 INFO - PID 23750 | 18:40:27 INFO - PID 23750 | Cycle 1(1): loaded http://localhost:55999/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55999/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:40:28 INFO - PID 23750 | RSS: Main: 184967168 18:40:28 INFO - PID 23750 | 18:40:30 INFO - PID 23750 | Cycle 1(1): loaded http://localhost:55999/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55999/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:40:30 INFO - PID 23750 | RSS: Main: 184954880 18:40:30 INFO - PID 23750 | 18:40:32 INFO - PID 23750 | Cycle 1(1): loaded http://localhost:55999/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55999/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:40:33 INFO - PID 23750 | RSS: Main: 184320000 18:40:33 INFO - PID 23750 | 18:40:36 INFO - PID 23750 | Cycle 1(1): loaded http://localhost:55999/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55999/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:40:36 INFO - PID 23750 | RSS: Main: 185565184 18:40:36 INFO - PID 23750 | 18:40:39 INFO - PID 23750 | Cycle 1(1): loaded http://localhost:55999/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55999/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:40:40 INFO - PID 23750 | RSS: Main: 184836096 18:40:40 INFO - PID 23750 | 18:40:42 INFO - PID 23750 | Cycle 1(1): loaded http://localhost:55999/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55999/tests/kraken/driver.html?testName=ai-astar) 18:40:42 INFO - PID 23750 | RSS: Main: 186294272 18:40:42 INFO - PID 23750 | 18:40:42 INFO - PID 23750 | __start_tp_report 18:40:42 INFO - PID 23750 | _x_x_mozilla_page_load 18:40:42 INFO - PID 23750 | _x_x_mozilla_page_load_details 18:40:42 INFO - PID 23750 | |i|pagename|runs| 18:40:42 INFO - PID 23750 | |0;ai-astar;107;112;120;112;109;114;108;114;113;114 18:40:42 INFO - PID 23750 | |1;audio-beat-detection;129;131;119;119;149;122;125;120;125;116 18:40:42 INFO - PID 23750 | |2;audio-dft;170;173;166;163;164;182;173;166;173;167 18:40:42 INFO - PID 23750 | |3;audio-fft;79;74;77;78;75;79;78;82;77;78 18:40:42 INFO - PID 23750 | |4;audio-oscillator;82;82;80;82;79;86;81;79;83;88 18:40:42 INFO - PID 23750 | |5;imaging-gaussian-blur;113;122;115;119;111;116;115;113;117;110 18:40:42 INFO - PID 23750 | |6;imaging-darkroom;155;149;149;147;152;153;150;149;154;148 18:40:42 INFO - PID 23750 | |7;imaging-desaturate;105;96;100;94;94;95;99;101;94;95 18:40:42 INFO - PID 23750 | |8;json-parse-financial;55;52;53;54;57;54;55;55;54;53 18:40:42 INFO - PID 23750 | |9;json-stringify-tinderbox;38;44;46;40;39;39;38;40;43;38 18:40:42 INFO - PID 23750 | |10;stanford-crypto-aes;76;74;73;75;73;75;73;79;75;74 18:40:42 INFO - PID 23750 | |11;stanford-crypto-ccm;120;115;119;111;121;117;118;121;117;122 18:40:42 INFO - PID 23750 | |12;stanford-crypto-pbkdf2;176;173;173;178;169;167;169;173;172;180 18:40:42 INFO - PID 23750 | |13;stanford-crypto-sha256-iterative;69;74;68;66;67;66;68;66;74;66 18:40:42 INFO - PID 23750 | __end_tp_report 18:40:42 INFO - PID 23750 | __start_cc_report 18:40:42 INFO - PID 23750 | _x_x_mozilla_cycle_collect,748 18:40:42 INFO - PID 23750 | __end_cc_report 18:40:42 INFO - PID 23750 | __startTimestamp1505958042500__endTimestamp 18:40:42 INFO - PID 23750 | 18:40:42 INFO - PID 23750 | ------- Summary: start ------- 18:40:42 INFO - PID 23750 | Number of tests: 14 18:40:42 INFO - PID 23750 | 18:40:42 INFO - PID 23750 | [#0] ai-astar Cycles:10 Average:112.30 Median:113.50 stddev:3.74 (3.3%) stddev-sans-first:3.44 18:40:42 INFO - PID 23750 | Values: 107.0 112.0 120.0 112.0 109.0 114.0 108.0 114.0 113.0 114.0 18:40:42 INFO - PID 23750 | 18:40:42 INFO - PID 23750 | [#1] audio-beat-detection Cycles:10 Average:125.50 Median:125.00 stddev:9.50 (7.6%) stddev-sans-first:9.99 18:40:42 INFO - PID 23750 | Values: 129.0 131.0 119.0 119.0 149.0 122.0 125.0 120.0 125.0 116.0 18:40:42 INFO - PID 23750 | 18:40:42 INFO - PID 23750 | [#2] audio-dft Cycles:10 Average:169.70 Median:171.50 stddev:5.74 (3.3%) stddev-sans-first:6.08 18:40:42 INFO - PID 23750 | Values: 170.0 173.0 166.0 163.0 164.0 182.0 173.0 166.0 173.0 167.0 18:40:42 INFO - PID 23750 | 18:40:42 INFO - PID 23750 | [#3] audio-fft Cycles:10 Average:77.70 Median:78.00 stddev:2.21 (2.8%) stddev-sans-first:2.30 18:40:42 INFO - PID 23750 | Values: 79.0 74.0 77.0 78.0 75.0 79.0 78.0 82.0 77.0 78.0 18:40:42 INFO - PID 23750 | 18:40:42 INFO - PID 23750 | [#4] audio-oscillator Cycles:10 Average:82.20 Median:82.00 stddev:2.90 (3.5%) stddev-sans-first:3.07 18:40:42 INFO - PID 23750 | Values: 82.0 82.0 80.0 82.0 79.0 86.0 81.0 79.0 83.0 88.0 18:40:42 INFO - PID 23750 | 18:40:42 INFO - PID 23750 | [#5] imaging-gaussian-blur Cycles:10 Average:115.10 Median:115.50 stddev:3.63 (3.1%) stddev-sans-first:3.77 18:40:42 INFO - PID 23750 | Values: 113.0 122.0 115.0 119.0 111.0 116.0 115.0 113.0 117.0 110.0 18:40:42 INFO - PID 23750 | 18:40:42 INFO - PID 23750 | [#6] imaging-darkroom Cycles:10 Average:150.60 Median:151.00 stddev:2.72 (1.8%) stddev-sans-first:2.37 18:40:42 INFO - PID 23750 | Values: 155.0 149.0 149.0 147.0 152.0 153.0 150.0 149.0 154.0 148.0 18:40:42 INFO - PID 23750 | 18:40:42 INFO - PID 23750 | [#7] imaging-desaturate Cycles:10 Average:97.30 Median:94.50 stddev:3.77 (4.0%) stddev-sans-first:2.79 18:40:42 INFO - PID 23750 | Values: 105.0 96.0 100.0 94.0 94.0 95.0 99.0 101.0 94.0 95.0 18:40:42 INFO - PID 23750 | 18:40:42 INFO - PID 23750 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.50 stddev:1.40 (2.6%) stddev-sans-first:1.45 18:40:42 INFO - PID 23750 | Values: 55.0 52.0 53.0 54.0 57.0 54.0 55.0 55.0 54.0 53.0 18:40:42 INFO - PID 23750 | 18:40:42 INFO - PID 23750 | [#9] json-stringify-tinderbox Cycles:10 Average:40.50 Median:40.00 stddev:2.84 (7.1%) stddev-sans-first:2.86 18:40:42 INFO - PID 23750 | Values: 38.0 44.0 46.0 40.0 39.0 39.0 38.0 40.0 43.0 38.0 18:40:42 INFO - PID 23750 | 18:40:42 INFO - PID 23750 | [#10] stanford-crypto-aes Cycles:10 Average:74.70 Median:75.00 stddev:1.83 (2.4%) stddev-sans-first:1.88 18:40:42 INFO - PID 23750 | Values: 76.0 74.0 73.0 75.0 73.0 75.0 73.0 79.0 75.0 74.0 18:40:42 INFO - PID 23750 | 18:40:42 INFO - PID 23750 | [#11] stanford-crypto-ccm Cycles:10 Average:118.10 Median:119.50 stddev:3.31 (2.8%) stddev-sans-first:3.44 18:40:42 INFO - PID 23750 | Values: 120.0 115.0 119.0 111.0 121.0 117.0 118.0 121.0 117.0 122.0 18:40:42 INFO - PID 23750 | 18:40:42 INFO - PID 23750 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.00 Median:173.00 stddev:4.11 (2.4%) stddev-sans-first:4.21 18:40:42 INFO - PID 23750 | Values: 176.0 173.0 173.0 178.0 169.0 167.0 169.0 173.0 172.0 180.0 18:40:42 INFO - PID 23750 | 18:40:42 INFO - PID 23750 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.00 stddev:3.13 (4.6%) stddev-sans-first:3.32 18:40:42 INFO - PID 23750 | Values: 69.0 74.0 68.0 66.0 67.0 66.0 68.0 66.0 74.0 66.0 18:40:42 INFO - PID 23750 | -------- Summary: end -------- 18:40:42 INFO - PID 23750 | 18:40:43 INFO - PID 23750 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:40:43 INFO - TEST-INFO | 23750: exit 0 18:40:43 INFO - TEST-OK | kraken | took 62561ms 18:40:43 INFO - SUITE-END | took 1175s 18:40:43 INFO - Completed test suite (00:19:35) 18:40:43 INFO - javascript benchmark 18:40:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8503.73995477384, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3104.2239440139965, 3130.0766273949184, 3136.2943337748993, 3148.370325934812, 3173.4922412414003, 6153.53848461366, 6229.688515574226, 6260.060990851369, 6310.463610593094, 6349.079383489391, 6230.875382492348, 6247.812565623027, 6266.1540692006565, 6272.466840318567, 6320.557560970722, 5735.2544169257935, 5749.568782341341, 5783.884322313551, 5790.30516338039, 5792.623479473821, 5851.678157701324, 5871.677057761822, 5894.17481552583, 5895.145203945425, 5958.910616340759, 5827.941720582782, 5844.576093991126, 5909.11363295507, 5915.290165180184, 5923.348431672513, 1229.7567292902988, 5694.373618901917, 5719.28508936383, 5723.998300297431, 5807.30312362515, 5741.081426971665, 5746.597738158331, 5780.017397042513, 5823.126531020339, 5838.38696936824, 6216.191895053659, 6234.0, 6265.592736472115, 6322.968385158045, 6384.48924086072, 6694.364035416627, 6702.932970670279, 6725.2602213756445, 6793.796186114425, 6815.829604259884, 6747.460203183734, 6779.762708305186, 6792.966035169842, 6843.555168914005, 6890.827729306758, 6110.419510146529, 6220.68896555175, 6298.149749783766, 6314.273858506284, 6339.2392912850755, 6768.289329620417, 6917.0, 6966.895496567556, 7023.367896889305, 7043.612601306977, 6587.83530411734, 6623.801285961429, 6645.0, 6686.632235227109, 6706.329367063255, 6657.068010478537, 6664.233613134455, 6742.797716068526, 6793.830154246183, 6817.284185160586, 6705.295943925915, 6709.463242940553, 6761.256261811197, 6766.086578311963, 6814.557053791586, 6455.193100862392, 6586.440152586988, 6614.0740296358545, 6621.106150669694, 6742.42689371409, 5154.5103215194495, 5171.3277273954145, 5199.324087868628, 5211.296474975906, 5219.269302297681, 4334.1981733379425, 4554.180247555406, 4651.0, 4686.133065382915, 4710.081534100886, 5756.65460072397, 5795.710214489259, 5805.129230615357, 5820.621659592113, 5848.707564621752, 6670.733170673216, 6710.295418981034, 6775.525713200029, 6785.4732685145445, 6854.177498700088, 5603.971980140154, 5643.774249030075, 5709.74306156224, 5737.3975732548315, 5811.302643682785, 5733.226014488074, 5745.0, 5794.82615521535, 5799.217105690762, 5811.27359080115, 6447.677616119176, 6449.451796597342, 6459.289478157399, 6471.9029214561815, 6497.0, 6754.5947243165565, 6776.45788336922, 6809.3871551559605, 6814.1482314710665, 6853.314668533306, 6466.4503517201565, 6550.737970481127, 6552.475801935739, 6566.409023187936, 6568.1789776277965, 6553.606783593, 6642.202935647753, 6648.800535983928, 6692.83267918306, 6746.325367463214, 6577.50668699859, 6620.735170593315, 6709.329067093252, 6711.697973591297, 6794.422474089758, 6350.777722779865, 6417.871642567029, 6429.58207716497, 6449.161608990801, 6457.580257283449, 6400.55196136266, 6424.614523128627, 6514.511411644051, 6532.248791388861, 6600.636964966973, 6109.816705498843, 6159.599626024294, 6426.0, 6428.871422571616, 6504.544681872224, 6360.77737279193, 6442.194725659293, 6448.290688024407, 6465.806025819233, 6522.934770652233, 6249.937500624936, 6299.055141728777, 6323.051542268512, 6324.936750632435, 6339.936600633934], "value": 6077.5844220705, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [864.8789169516267, 889.7686601483613, 890.888638920135, 894.253298495756, 896.7892545251865, 37440.0, 40382.0, 40656.796716016375, 40860.0, 40935.79532102339, 36860.447093293624, 37222.255554888885, 37295.06762330943, 37356.626433735655, 37372.62627373725, 7192.820179495502, 7280.126384833828, 7324.08448943882, 7352.632368381586, 7357.484976051678, 7151.4993950423495, 7213.675384607679, 7238.276172382746, 7250.492465527415, 7305.342519173273, 7223.747168849091, 7279.381252593509, 7280.8179795505275, 7291.744788932362, 7293.416526677879, 7170.641467926583, 7843.7254696085365, 8002.119766825645, 8005.439619226657, 8009.519428834288, 7817.570033648147, 7886.408519361071, 7886.64510097047, 7895.368370530344, 7907.604619769046, 38245.0, 38406.615933840585, 38654.61345386566, 39104.60895391038, 39200.41199382012, 3527.0, 3602.7117830573493, 3620.6922411594783, 3622.5290712207507, 3626.2928728897878, 31015.84492077548, 31229.687703122905, 31333.216669583217, 31360.686393136006, 31569.0, 5112.054269960069, 5166.535011848951, 5202.271681964528, 5219.347581552306, 5242.947570524284, 188003.0, 190845.0, 191418.04290978456, 191614.0, 194110.0, 41542.37686434706, 42503.36244956328, 46497.0, 46917.29624055642, 46940.0, 49741.502584973685, 50350.0, 50496.990060198594, 50800.491995079574, 50893.74553127211, 15876.761848572281, 15900.681986360209, 16100.355985760672, 16112.597185070232, 16114.0, 12951.5466958656, 13073.215607063607, 13106.80339794904, 13113.737725245443, 13155.671108222372, 13335.199888006751, 13398.0, 13449.125806822525, 13463.932680336535, 13586.320683965761, 5347.545458635982, 5468.535174510132, 5480.862978425571, 5508.366537848199, 5527.613067085346, 1003.6236411345745, 1005.3967619428284, 1008.1531513528671, 1010.3331801011298, 1030.0677886512717, 22683.773162268168, 23145.652815207784, 23188.420289492897, 23203.187888423825, 23239.3028209154, 1117.4468638024068, 1125.9324440533594, 1126.301692950376, 1128.1482480727, 1128.9322640641587, 12177.452014659362, 12186.08604354677, 12222.083343749255, 12245.510179592895, 12434.75130497403, 226622.0, 227213.0, 227570.0, 229607.0, 229901.0, 51926.48073519292, 52106.0, 52235.0, 52600.47399526032, 52743.47256527462, 1393.5053056165118, 1465.9560213193622, 1482.3922191901484, 1483.9035462695106, 1486.5094518808983, 22263.55472890501, 22669.319920402413, 22795.202167924042, 22812.543749124594, 22992.655110173026, 37245.0, 37248.06879828026, 37261.62738372581, 37274.813625931696, 37409.81295093616, 193269.0, 193462.0, 194274.0, 194458.0, 194525.0, 29590.852045739633, 30031.0, 31376.0, 32575.0, 33749.831250844574, 42300.0, 42431.7878410606, 42768.572314277706, 43222.78388608037, 43384.566154339314, 31716.36567268688, 31722.68277317197, 31945.36109277848, 32060.0, 32601.510977334885, 31145.8442707785, 31251.463153438643, 31477.842610787717, 31519.0, 31626.0, 35770.8211458941, 35790.28419431649, 35961.82019089888, 36002.0, 36116.09709757277, 30290.394192115593, 31050.534241985937, 31613.683863161074, 31653.683463165995, 32055.198620034687, 22857.88571057134, 23375.649365259877, 23484.0, 23583.64624530599, 23899.402514937265], "value": 16889.35782873882, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.7203578300305, 589.313449830947, 590.3093380744529, 607.7174114036973, 612.5497759147026, 21772.673409898864, 22376.32871013868, 22571.54856902861, 22678.659820102712, 22739.20412785553, 22509.0, 23997.64003539944, 24083.518329633396, 24252.27243182703, 24265.636015459782, 6593.472522198224, 6710.261871194165, 6736.259011508755, 6736.326367363249, 6755.5271131020845, 6626.834329141786, 6693.531452798306, 6695.464362850985, 6695.799126026227, 6727.461803055744, 6309.3375195604485, 6570.540062195648, 6583.473322134218, 6585.670716464181, 6585.769498067569, 6519.022146678012, 6548.9017664735065, 6567.54027218095, 6572.112764776742, 6590.5716128451495, 5856.00447923854, 6050.636961782263, 6068.362821903681, 6078.483328917047, 6113.938860611426, 27419.31451713723, 27698.5845212322, 27758.44483110347, 28028.71971280302, 28047.0, 2016.0, 2056.9177232910665, 2061.2373421833868, 2062.7730949595534, 2064.64900966836, 17997.460076197734, 18496.352627658103, 18566.16452259638, 18660.440186794283, 18847.71728424075, 2259.0, 2361.031976889467, 2361.7756313150394, 2369.9289021329387, 2375.857448553075, 123254.38372808168, 128108.0, 128835.35582322028, 132468.01297980538, 136578.63421365758, 14483.927580362242, 14718.264086795618, 14739.115653060637, 14940.947824786877, 14963.027403218755, 22595.661065084038, 22799.544009120058, 22934.19730309431, 23024.53950920972, 23055.88472057629, 10021.899781002243, 10027.548760305805, 10081.899181008243, 10333.431661258703, 10411.219158562986, 9519.143277105093, 9519.286053546011, 9536.141747242782, 9573.569189386495, 9640.662576809778, 8804.559772011375, 8887.178128483645, 8998.415103018282, 9012.0, 9093.136152065543, 2556.105363122892, 2647.3454091193003, 2728.126999360186, 2735.097417852104, 2741.2461573067567, 940.8071345374182, 959.2565761534867, 968.3657204531044, 975.6341371985505, 975.697533764521, 18704.158312875952, 18961.905190474234, 19253.0, 19344.613107737765, 19536.02319884, 1010.9848352274722, 1014.8985101489792, 1029.3669393323203, 1035.6271742172812, 1046.26238501856, 10639.574417023387, 10655.987681170276, 10701.0, 10774.784504310026, 10947.193713037419, 463009.0, 466408.0, 470540.0, 487185.0, 489046.0, 112069.43965280284, 112902.0, 114392.0, 114669.0, 114953.0, 1138.5066703918392, 1181.9704507387212, 1240.3736113262926, 1248.5193200617646, 1259.7102666386781, 19872.205111795367, 20141.899290503454, 20473.283435079702, 20511.8974405127, 20691.793082069587, 57600.423995759505, 58982.41017589769, 60240.698796505734, 60332.69833650804, 60392.396076038676, 73905.63047184945, 75696.62151689206, 76040.0, 77437.0, 77548.0, 14245.0, 14398.856011439751, 14508.0, 14509.274536272933, 14515.346809393392, 21641.675374869075, 21729.13083476643, 21827.12691492386, 22063.0, 22225.888870555544, 16719.49841504757, 17546.298148073933, 17750.0, 18011.279548818424, 18135.637287253918, 14351.282435878373, 14988.175650339335, 15098.094114353175, 15871.127088010271, 16575.336986520404, 18410.0, 18464.984425856706, 18482.0, 18578.721319179953, 18740.531486712396, 14267.286635668383, 14365.497207597684, 14438.927805361327, 14466.782998255245, 14486.0, 17125.314987400365, 17266.136693165543, 17509.124543772505, 17681.734773978143, 17812.287508499514], "value": 11860.701862312406, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1345.381124682646, 1384.072671310222, 1393.6655202751342, 1401.8598140185982, 1417.82986041675, 15045.699086018287, 15650.139242341667, 15651.843481565194, 15678.294476748546, 15699.529014129594, 15296.464623738171, 15495.535133945972, 15512.30194641241, 15513.379464821423, 15537.37850485982, 2760.903368382107, 2851.7576006039512, 2866.956995645067, 2876.856157192132, 2896.1745902417815, 2747.697753247141, 2846.786491013172, 2867.928301792451, 2870.3111253299267, 2876.5109931311727, 2807.8736456859388, 2842.2610121368384, 2858.7141285871453, 2875.7555607773365, 2884.985575072132, 2757.3382388226782, 2763.599278104684, 2782.526970415024, 2784.8886044558194, 2786.303424143964, 2695.824771389873, 2704.7971402144917, 2706.323419145214, 2715.7827373810246, 2722.3194201449637, 14584.562463126123, 14739.852601474062, 14739.92630036843, 14996.250187490581, 15060.849391506054, 8037.477563958324, 8298.917010829935, 8307.958460207721, 8363.20549547792, 8366.874496882552, 5404.810831620914, 5621.718914054321, 5629.690367029812, 5985.610935289235, 6035.124906888521, 2021.8786872787532, 2026.1996511377922, 2029.6853987631794, 2032.6646103392923, 2071.3682326890294, 24459.63310550343, 25258.873705631355, 27888.0, 28006.719932800817, 28740.712592874013, 7494.437917156126, 7651.808704782425, 7667.654955527015, 7800.375969922506, 7806.102298235662, 20306.593868122556, 20470.692939605917, 20478.795212047986, 20491.897540512204, 20500.89749551243, 8350.373721970876, 8470.915290847135, 8582.356323275779, 8715.738527844174, 8724.738257852274, 7569.507981981264, 7664.73173438927, 7673.923260767321, 7808.5314881107315, 7926.207379262028, 5705.914411283834, 5815.767369305265, 5838.620489668158, 5843.1235314703135, 5961.910571341434, 1742.364037126465, 1751.0719318761078, 1751.6934536456324, 1767.169430367725, 1799.9910000450177, 909.0909090909091, 919.6965001549606, 928.7445952363154, 934.7896723237217, 934.9252059835197, 7552.207018263113, 7552.471327007167, 7622.0, 7670.309672129535, 7687.615619219128, 713.3900515059653, 719.3777382564044, 734.6987735028613, 737.8782500887401, 749.3181205103439, 4533.274676051816, 4598.287265473857, 4648.744319062416, 4691.132140554008, 4700.106979673851, 21395.572088558452, 22669.773302266767, 22682.0, 22687.319380418612, 22718.886405568195, 21443.892780535996, 22061.889690551765, 22197.445063873532, 22221.333359999226, 22245.332640020824, 9826.508674566243, 10630.415327157081, 11261.71845703864, 11410.543578256942, 11444.027257683023, 8525.062243153125, 8724.781880452785, 8909.599068042084, 8915.331350148635, 8942.776430589027, 18031.44283186857, 18036.819631803515, 18596.32633063031, 18925.526861828563, 18932.526686832938, 18678.692491525464, 19287.421377358703, 19324.0, 19701.014949252192, 19814.90092549586, 11179.66461006171, 11616.186866919235, 11766.882331176921, 11786.762389949276, 11816.113791465847, 13350.732985340434, 13487.32563371847, 13563.86436135626, 13571.1857288563, 13620.387082581507, 11078.335299882034, 11350.375729334966, 11416.543338266709, 11472.082233421474, 11526.94236528812, 10662.8400573993, 11067.169962252703, 11180.832287515856, 11247.18320767059, 11432.199746017699, 10086.89913100889, 10088.69733907984, 10140.391576505433, 10144.391336519833, 10147.949260253947, 7071.646417679199, 7100.218975912542, 7114.715411383487, 7118.679659415235, 7319.865420859775, 6646.335366463315, 6859.622720750406, 6909.550879192836, 6959.060526828814, 6998.860022799618], "value": 6805.773155405641, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.6183738521341, 512.5335944290696, 528.8069854503108, 532.6404676843131, 539.2558269587968, 6108.908366374502, 6158.353372895837, 6177.691115444227, 6217.347178546244, 6258.593191442559, 6083.300420451649, 6093.817185484431, 6137.202163718722, 6139.171211886394, 6162.22972128484, 5070.974645126769, 5100.2859599655985, 5111.575244586118, 5121.3342265505435, 5123.948760512384, 5090.567301779353, 5116.334876466054, 5134.435212126663, 5145.3568303962, 5186.1442861927735, 4747.4540427850725, 4852.199387101124, 4893.339399181118, 4915.139850526161, 4946.406431228258, 4888.926666100011, 4899.265110233458, 4910.680805747615, 4961.776720047606, 4971.6271279654175, 4411.558844115595, 4424.80088396023, 4435.866923992286, 4480.305552639346, 4494.280915053609, 5935.436133567304, 5954.0, 5961.403859614047, 5966.731497082642, 6017.849553761191, 5560.193771903092, 5599.692016939067, 5625.46558076982, 5671.943280567183, 5682.346530149044, 6272.372762723737, 6324.588901721373, 6355.3009168991375, 6375.8087257381885, 6415.230172379298, 4476.641868650533, 4713.858584242478, 4772.832950846739, 4777.116233496814, 4819.373481447389, 6466.861208826355, 6482.805515834485, 6584.374484423972, 6652.201735791639, 6679.33206679333, 4867.537583929521, 4929.605631549467, 4936.407631084221, 4947.367368768298, 4948.727819969864, 4958.752062396866, 4970.478099799469, 4998.300237966688, 5025.271335656309, 5028.566858445325, 5462.781488740406, 5510.393856675762, 5540.786343920517, 5544.805931792368, 5599.272094627672, 6336.841578960563, 6357.014662727284, 6372.5833041914, 6409.391107844747, 6484.0, 1704.5397742609425, 1709.9230534625972, 1720.5977128640118, 1737.9391721289694, 1740.704080306351, 1162.7790719763386, 1212.7877621416217, 1235.9814602780966, 1243.657994051643, 1252.3487786351047, 5472.808451704172, 5498.092814685612, 5533.142362933751, 5566.471185237396, 5568.359638641608, 6393.296737358887, 6415.743370265231, 6460.935390646127, 6467.288598254188, 6489.442533791856, 5480.479354461319, 5500.202470641814, 5518.936989970605, 5552.41699621542, 5571.359293681197, 3400.897973060812, 3438.3639026780124, 3450.861965521351, 3462.7492130115625, 3472.9652703472643, 6256.280527739277, 6268.49852011838, 6284.591501552384, 6307.842303942438, 6335.796198722228, 6611.338866113447, 6681.832954176184, 6703.463722902156, 6720.193576770819, 6729.629870357079, 5883.588148829626, 6071.726772295346, 6236.6258024518675, 6254.21822272216, 6274.46667033289, 6393.456556192589, 6404.231492220963, 6414.227195524066, 6446.871062578816, 6466.288708242184, 6347.809565713036, 6371.6814159290925, 6424.421802037839, 6456.192975878015, 6465.288818230085, 6163.691815409122, 6188.350223226496, 6189.7833575826435, 6242.843928901813, 6273.341299163704, 4796.56830885222, 4824.6863953842885, 4923.679960802537, 4942.75286235694, 4952.761809547613, 6300.432961033529, 6466.321036291121, 6477.838054048498, 6516.967415162894, 6545.247296560957, 6225.533085018732, 6382.5532212744665, 6391.712372943136, 6396.552241343062, 6415.390537898892, 6334.954732469091, 6350.333215012357, 6425.1968503937005, 6430.2926678066315, 6435.967820160869, 6189.226346706661, 6228.377162283736, 6311.936880631135, 6332.366763323631, 6345.111684364285, 6247.156633854372, 6288.937110629018, 6350.936490635219, 6441.645709486023, 6518.43557546195, 6159.969200153971, 6166.784162554289, 6193.049189505291, 6197.845053873689, 6289.308176100717], "value": 4942.359477146814, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [889.2174886100231, 923.7967647117633, 930.3115694386156, 934.8737920380746, 949.9002604726503, 19122.713159302602, 19786.802131978675, 19787.406377808653, 19864.801351986476, 19892.40322790315, 15779.1321477319, 15903.045817250973, 15924.840751592481, 15984.0, 16312.592185195375, 947.5877993073001, 965.5268918230087, 972.2999440402899, 976.0483528559633, 977.6480467031863, 971.4608392342247, 976.3360914578084, 976.3556053005018, 981.4160574458186, 981.6318880419842, 981.4454833019357, 1007.7934023525196, 1008.8234558952154, 1013.3058854684565, 1014.5028935821468, 1003.9799204015916, 1004.693568461619, 1005.215931573374, 1009.4599389326748, 1012.7265638277661, 717.9353858152791, 740.2597402597403, 746.6490749347798, 749.9887501687424, 751.0, 19405.0, 19429.80570194294, 19519.80480195194, 19572.31496897616, 19617.803821961883, 21858.344249672537, 21957.0, 22400.21599244035, 22490.887545562167, 22751.317460476213, 17249.22378492971, 17597.648047039114, 17746.467605971713, 17792.3772667956, 17932.641347173114, 3637.7635453695425, 3649.6167902370134, 3650.1969566695284, 3653.1415117447377, 3659.396199627058, 138959.0, 141436.99999999898, 142974.0, 143552.28223858814, 143892.0, 15176.848231517764, 15319.693606127816, 15348.693026139637, 15671.608209794618, 15692.92153539225, 31223.219419514695, 31262.0, 31378.0, 31975.840120799712, 32332.0, 8551.914480855236, 8599.14008599135, 8678.913210867811, 8699.260562852229, 8702.260307873901, 4324.113556720865, 4350.6736994725525, 4376.059147283349, 4457.0, 4460.085682435093, 5273.894522109536, 5349.411564727877, 5384.19237114436, 5421.0, 5438.320209973754, 948.1135138645251, 963.8843338799248, 982.8771403574553, 984.778424854402, 985.763416779982, 690.4855882367668, 696.3036963036963, 710.0343532795395, 717.9928200718031, 718.1705130574195, 11457.484413201259, 11583.247088939192, 11586.826197607043, 12990.415431305613, 13411.731765364639, 708.7058870568657, 709.6558169287891, 719.8272414620453, 725.3399406540024, 728.2608152724988, 5594.860128496739, 5613.831585052455, 5627.296587926509, 5645.491905728504, 5700.572457065654, 117519.41240293744, 121575.39212303882, 121593.0, 121618.0, 122238.0, 100475.0795545947, 100718.4964075175, 104659.0, 112160.0, 112196.43901780438, 82617.58691206506, 85899.5705021471, 87389.99999999873, 88006.0, 90185.54907225553, 12887.29119898377, 13235.80146297787, 13384.531541396005, 13394.397252123483, 13624.795628065389, 41215.0, 43106.13787724291, 43969.0, 44053.77973110114, 44509.33236001527, 55921.44078559162, 56725.71637141953, 56794.43205567892, 56904.71547642235, 58111.418885812294, 17945.0, 18197.54506137357, 18280.177392017657, 18360.0, 18444.81555184431, 21244.468888277916, 21474.14103435845, 21551.137954481645, 21561.24535641245, 21568.137274508845, 19726.704099438808, 19871.801281986995, 19890.204391824165, 19929.800701992794, 20127.899360503103, 18140.546486337946, 18328.17523211432, 18589.9070504652, 18625.53436164107, 18720.0, 18510.352137675116, 18970.24119035223, 19240.615187696447, 19402.514937126685, 19739.506512337306, 13883.514077007256, 13900.582982510541, 13972.301384930915, 14336.139831610137, 14435.566932992027, 13955.720885582434, 14062.718745625234, 14144.929275353557, 14145.575632731034, 14275.500357487437], "value": 9233.522757567598, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1459.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 112.0, 120.0, 112.0, 109.0, 114.0, 108.0, 114.0, 113.0, 114.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 131.0, 119.0, 119.0, 149.0, 122.0, 125.0, 120.0, 125.0, 116.0], "value": 125.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 173.0, 166.0, 163.0, 164.0, 182.0, 173.0, 166.0, 173.0, 167.0], "value": 169.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 77.0, 78.0, 75.0, 79.0, 78.0, 82.0, 77.0, 78.0], "value": 77.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 80.0, 82.0, 79.0, 86.0, 81.0, 79.0, 83.0, 88.0], "value": 82.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 122.0, 115.0, 119.0, 111.0, 116.0, 115.0, 113.0, 117.0, 110.0], "value": 115.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 149.0, 149.0, 147.0, 152.0, 153.0, 150.0, 149.0, 154.0, 148.0], "value": 150.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 96.0, 100.0, 94.0, 94.0, 95.0, 99.0, 101.0, 94.0, 95.0], "value": 97.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 53.0, 54.0, 57.0, 54.0, 55.0, 55.0, 54.0, 53.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 44.0, 46.0, 40.0, 39.0, 39.0, 38.0, 40.0, 43.0, 38.0], "value": 40.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 73.0, 75.0, 73.0, 75.0, 73.0, 79.0, 75.0, 74.0], "value": 74.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 115.0, 119.0, 111.0, 121.0, 117.0, 118.0, 121.0, 117.0, 122.0], "value": 118.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 173.0, 173.0, 178.0, 169.0, 167.0, 169.0, 173.0, 172.0, 180.0], "value": 173.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 68.0, 66.0, 67.0, 66.0, 68.0, 66.0, 74.0, 66.0], "value": 68.4, "unit": "score"}]}]} 18:40:43 INFO - Return code: 0 18:40:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:40:43 INFO - # TBPL SUCCESS # 18:40:43 INFO - Running post-action listener: _package_coverage_data 18:40:43 INFO - Running post-action listener: _resource_record_post_action 18:40:43 INFO - [mozharness: 2017-09-21 01:40:43.796103Z] Finished run-tests step (success) 18:40:43 INFO - Running post-run listener: _resource_record_post_run 18:40:43 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 634880; Write bytes: 344432640; Read time: 1004; Write time: 294228 18:40:43 INFO - TinderboxPrint: CPU usage
13.3% 18:40:43 INFO - TinderboxPrint: I/O read bytes / time
634,880 / 1,004 18:40:43 INFO - TinderboxPrint: I/O write bytes / time
344,432,640 / 294,228 18:40:43 INFO - TinderboxPrint: CPU idle
8,250.3 (86.7%) 18:40:43 INFO - TinderboxPrint: CPU user
1,201.4 (12.6%) 18:40:43 INFO - TinderboxPrint: Swap in / out
0 / 0 18:40:43 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10510336; Read time: 0; Write time: 1156 18:40:43 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:40:43 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 630784; Write bytes: 333922304; Read time: 948; Write time: 293072 18:40:44 INFO - Running post-run listener: _upload_blobber_files 18:40:44 INFO - Blob upload gear active. 18:40:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:40:44 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:40:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:40:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:40:44 INFO - (blobuploader) - INFO - Open directory for files ... 18:40:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:40:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:40:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:40:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:40:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:40:45 INFO - (blobuploader) - INFO - Done attempting. 18:40:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:40:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:40:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:40:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:40:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:40:52 INFO - (blobuploader) - INFO - Done attempting. 18:40:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:40:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:40:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:40:53 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:40:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:40:53 INFO - (blobuploader) - INFO - Done attempting. 18:40:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:40:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:40:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:40:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:40:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:40:54 INFO - (blobuploader) - INFO - Done attempting. 18:40:54 INFO - (blobuploader) - INFO - Iteration through files over. 18:40:54 INFO - Return code: 0 18:40:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:40:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:40:54 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50dfba25ab07b242d11b2cee39cbf574ddce0f7e2d7d9c7c29507925a59af3555cf941ee95691569a759c47aa42970a930750522f79b1f7dfbdc5cf046506a0d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/697a9b0dadccd61bad6cb8b05fbe0b6263d62c7b90153e23e76b0a5aed3ce80deab6a22b6b4fda039f3804277ad7565f6150572a65cb7e617513b92abf5bbc2d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b379f699c4ba4465ebb4467baa2f562a5997f28eee5d9e9930cd2d29fd45ec2a92512a6f5d7d785b74b8b48f614752a76f30a4b70d33fd8f3ebb6e50e228e49", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/041a4faca861200dbafc7dddf244ccd6003ffbe475e4e49916c2fa503362340d2e26c06715dcc52fcd25bac223f22d3c67489a874ee56ef3e66d0a8483e56510"} 18:40:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:40:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:40:54 INFO - Contents: 18:40:54 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50dfba25ab07b242d11b2cee39cbf574ddce0f7e2d7d9c7c29507925a59af3555cf941ee95691569a759c47aa42970a930750522f79b1f7dfbdc5cf046506a0d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/697a9b0dadccd61bad6cb8b05fbe0b6263d62c7b90153e23e76b0a5aed3ce80deab6a22b6b4fda039f3804277ad7565f6150572a65cb7e617513b92abf5bbc2d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b379f699c4ba4465ebb4467baa2f562a5997f28eee5d9e9930cd2d29fd45ec2a92512a6f5d7d785b74b8b48f614752a76f30a4b70d33fd8f3ebb6e50e228e49", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/041a4faca861200dbafc7dddf244ccd6003ffbe475e4e49916c2fa503362340d2e26c06715dcc52fcd25bac223f22d3c67489a874ee56ef3e66d0a8483e56510"} 18:40:54 INFO - Running post-run listener: copy_logs_to_upload_dir 18:40:54 INFO - Copying logs to upload dir... 18:40:54 INFO - mkdir: /builds/slave/test/build/upload/logs 18:40:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.866919 ========= master_lag: -0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-09-20 18:40:55.002040) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-20 18:40:55.010204) ========= 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=9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50dfba25ab07b242d11b2cee39cbf574ddce0f7e2d7d9c7c29507925a59af3555cf941ee95691569a759c47aa42970a930750522f79b1f7dfbdc5cf046506a0d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/697a9b0dadccd61bad6cb8b05fbe0b6263d62c7b90153e23e76b0a5aed3ce80deab6a22b6b4fda039f3804277ad7565f6150572a65cb7e617513b92abf5bbc2d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b379f699c4ba4465ebb4467baa2f562a5997f28eee5d9e9930cd2d29fd45ec2a92512a6f5d7d785b74b8b48f614752a76f30a4b70d33fd8f3ebb6e50e228e49", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/041a4faca861200dbafc7dddf244ccd6003ffbe475e4e49916c2fa503362340d2e26c06715dcc52fcd25bac223f22d3c67489a874ee56ef3e66d0a8483e56510"} build_url:https://queue.taskcluster.net/v1/task/dyJtNxu0QmqzSHUsp_kA3w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011337 build_url: 'https://queue.taskcluster.net/v1/task/dyJtNxu0QmqzSHUsp_kA3w/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50dfba25ab07b242d11b2cee39cbf574ddce0f7e2d7d9c7c29507925a59af3555cf941ee95691569a759c47aa42970a930750522f79b1f7dfbdc5cf046506a0d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/697a9b0dadccd61bad6cb8b05fbe0b6263d62c7b90153e23e76b0a5aed3ce80deab6a22b6b4fda039f3804277ad7565f6150572a65cb7e617513b92abf5bbc2d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b379f699c4ba4465ebb4467baa2f562a5997f28eee5d9e9930cd2d29fd45ec2a92512a6f5d7d785b74b8b48f614752a76f30a4b70d33fd8f3ebb6e50e228e49", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/041a4faca861200dbafc7dddf244ccd6003ffbe475e4e49916c2fa503362340d2e26c06715dcc52fcd25bac223f22d3c67489a874ee56ef3e66d0a8483e56510"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-20 18:40:55.041277) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:40:55.041795) ========= 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=9f7544b6a75ada7b6b576032000001a7-1505956807.670172-2014019035 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004540 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:40:55.125068) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-20 18:40:55.125514) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-20 18:40:55.126081) ========= ========= Total master_lag: 0.10 =========