builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-062 starttime: 1504392641.51 results: success (0) revision: 39e9a6b187a3f71f46d02af000ff8203abe4fe71 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 15:50:41.507446) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 15:50:41.507949) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 15:50:41.508236) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 15:50:41.525738) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:50:41.526114) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-02 15:50:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 41.8M=0s 2017-09-02 15:50:41 (41.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.158106 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:50:41.701502) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:50:41.701896) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015797 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:50:41.752009) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-02 15:50:41.752431) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 39e9a6b187a3f71f46d02af000ff8203abe4fe71 --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 39e9a6b187a3f71f46d02af000ff8203abe4fe71 --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=a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-02 15:50:41,801 truncating revision to first 12 chars 2017-09-02 15:50:41,801 Setting DEBUG logging. 2017-09-02 15:50:41,801 attempt 1/10 2017-09-02 15:50:41,802 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/39e9a6b187a3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-02 15:50:42,594 unpacking tar archive at: mozilla-central-39e9a6b187a3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.082083 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-02 15:50:42.851031) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 15:50:42.851367) ========= script_repo_revision: 39e9a6b187a3f71f46d02af000ff8203abe4fe71 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 15:50:42.851841) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 15:50:42.852112) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 15:50:42.865914) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-09-02 15:50:42.866290) ========= /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=a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:50:43 INFO - MultiFileLogger online at 20170902 15:50:43 in /builds/slave/test 15:50:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:50:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:50:43 INFO - {'append_to_log': False, 15:50:43 INFO - 'base_work_dir': '/builds/slave/test', 15:50:43 INFO - 'blob_upload_branch': 'Firefox', 15:50:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:50:43 INFO - 'branch': 'Firefox', 15:50:43 INFO - 'buildbot_json_path': 'buildprops.json', 15:50:43 INFO - 'code_coverage': False, 15:50:43 INFO - 'config_files': ('talos/linux_config.py',), 15:50:43 INFO - 'default_actions': ('clobber', 15:50:43 INFO - 'read-buildbot-config', 15:50:43 INFO - 'download-and-extract', 15:50:43 INFO - 'populate-webroot', 15:50:43 INFO - 'create-virtualenv', 15:50:43 INFO - 'install', 15:50:43 INFO - 'setup-mitmproxy', 15:50:43 INFO - 'run-tests'), 15:50:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:50:43 INFO - 'disable_ccov_upload': False, 15:50:43 INFO - 'disable_stylo': False, 15:50:43 INFO - 'download_minidump_stackwalk': True, 15:50:43 INFO - 'download_symbols': 'ondemand', 15:50:43 INFO - 'enable_stylo': False, 15:50:43 INFO - 'enable_webrender': False, 15:50:43 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:50:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:50:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:50:43 INFO - 'gecko_profile': False, 15:50:43 INFO - 'gecko_profile_interval': 0, 15:50:43 INFO - 'installer_path': 'installer.exe', 15:50:43 INFO - 'log_level': 'info', 15:50:43 INFO - 'log_name': 'talos', 15:50:43 INFO - 'log_to_console': True, 15:50:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:50:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:50:43 INFO - 'opt_config_files': (), 15:50:43 INFO - 'pip_index': False, 15:50:43 INFO - 'suite': 'dromaeojs-e10s', 15:50:43 INFO - 'system_bits': '32', 15:50:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:50:43 INFO - 'title': 'talos-linux64-ix-062', 15:50:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:50:43 INFO - 'use_talos_json': True, 15:50:43 INFO - 'verify': 'False', 15:50:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:50:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:50:43 INFO - 'work_dir': 'build'} 15:50:43 INFO - [mozharness: 2017-09-02 22:50:43.060831Z] Running clobber step. 15:50:43 INFO - Running pre-action listener: _resource_record_pre_action 15:50:43 INFO - Running main action method: clobber 15:50:43 INFO - rmtree: /builds/slave/test/build 15:50:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:50:44 INFO - Running post-action listener: _resource_record_post_action 15:50:44 INFO - [mozharness: 2017-09-02 22:50:44.107717Z] Finished clobber step (success) 15:50:44 INFO - [mozharness: 2017-09-02 22:50:44.107815Z] Running read-buildbot-config step. 15:50:44 INFO - Running pre-action listener: _resource_record_pre_action 15:50:44 INFO - Running main action method: read_buildbot_config 15:50:44 INFO - Using buildbot properties: 15:50:44 INFO - { 15:50:44 INFO - "project": "", 15:50:44 INFO - "product": "firefox", 15:50:44 INFO - "who": "archaeopteryx@coole-files.de", 15:50:44 INFO - "installer_path": "public/build/target.tar.bz2", 15:50:44 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 15:50:44 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:50:44 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 15:50:44 INFO - "stage_platform": "linux64-stylo", 15:50:44 INFO - "basedir": "/builds/slave/test", 15:50:44 INFO - "buildnumber": 295, 15:50:44 INFO - "platform": "ubuntu64_hw_stylo", 15:50:44 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:50:44 INFO - "slavebuilddir": "test", 15:50:44 INFO - "taskId": "LZmYsiWxT9urCkIP95tXOw", 15:50:44 INFO - "branch": "mozilla-central", 15:50:44 INFO - "script_repo_revision": "production", 15:50:44 INFO - "revision": "39e9a6b187a3f71f46d02af000ff8203abe4fe71", 15:50:44 INFO - "slavename": "talos-linux64-ix-062", 15:50:44 INFO - "repo_path": "mozilla-central" 15:50:44 INFO - } 15:50:44 INFO - Finding installer, test and symbols from parent task. 15:50:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LZmYsiWxT9urCkIP95tXOw'}, attempt #1 15:50:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LZmYsiWxT9urCkIP95tXOw'}, attempt #1 15:50:45 INFO - Task dependencies: EForbR1USZeEN3w5edfxHw 15:50:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw'}, attempt #1 15:50:45 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.tar.bz2 15:50:45 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.test_packages.json 15:50:45 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.crashreporter-symbols.zip 15:50:45 INFO - Running post-action listener: _resource_record_post_action 15:50:45 INFO - [mozharness: 2017-09-02 22:50:45.471330Z] Finished read-buildbot-config step (success) 15:50:45 INFO - [mozharness: 2017-09-02 22:50:45.471545Z] Running download-and-extract step. 15:50:45 INFO - Running pre-action listener: _resource_record_pre_action 15:50:45 INFO - Running main action method: download_and_extract 15:50:45 INFO - mkdir: /builds/slave/test/build/tests 15:50:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:45 INFO - trying https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.test_packages.json 15:50:45 INFO - Downloading https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:50:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:50:46 INFO - Downloaded 989 bytes. 15:50:46 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:50:46 INFO - Using the following test package requirements: 15:50:46 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:50:46 INFO - u'common': [u'target.common.tests.zip'], 15:50:46 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:50:46 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:50:46 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:50:46 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:50:46 INFO - u'mozbase': [u'target.common.tests.zip'], 15:50:46 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:50:46 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:50:46 INFO - u'web-platform': [u'target.common.tests.zip', 15:50:46 INFO - u'target.web-platform.tests.tar.gz'], 15:50:46 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:50:46 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:50:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.common.tests.zip 15:50:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:50:46 INFO - Fetch https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.common.tests.zip into memory 15:50:47 INFO - Content-Length response header: 55022272 15:50:47 INFO - Bytes received: 55022272 15:50:57 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:50:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.common.tests.zip 15:50:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:50:57 INFO - Fetch https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.common.tests.zip into memory 15:50:59 INFO - Content-Length response header: 55022272 15:50:59 INFO - Bytes received: 55022272 15:51:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.talos.tests.zip 15:51:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:51:12 INFO - Fetch https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.talos.tests.zip into memory 15:51:13 INFO - Content-Length response header: 14496650 15:51:13 INFO - Bytes received: 14496650 15:51:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:13 INFO - trying https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.tar.bz2 15:51:13 INFO - Downloading https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:51:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:51:14 INFO - Downloaded 62762602 bytes. 15:51:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.tar.bz2 15:51:14 INFO - mkdir: /builds/slave/test/properties 15:51:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:51:14 INFO - Writing to file /builds/slave/test/properties/build_url 15:51:14 INFO - Contents: 15:51:14 INFO - build_url:https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.tar.bz2 15:51:14 INFO - Running post-action listener: _resource_record_post_action 15:51:14 INFO - Running post-action listener: find_tests_for_verification 15:51:14 INFO - Running post-action listener: set_extra_try_arguments 15:51:14 INFO - [mozharness: 2017-09-02 22:51:14.664656Z] Finished download-and-extract step (success) 15:51:14 INFO - [mozharness: 2017-09-02 22:51:14.664753Z] Running populate-webroot step. 15:51:14 INFO - Running pre-action listener: _resource_record_pre_action 15:51:14 INFO - Running main action method: populate_webroot 15:51:14 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:51:14 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:51:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:51:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:51:14 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:51:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:51:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:51:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:51:14 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:51:14 INFO - 'talos_options': ['--enable-stylo'], 15:51:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:51:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:51:14 INFO - 'tests': ['damp', 'tps']}, 15:51:14 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:51:14 INFO - 'talos_options': ['--enable-stylo'], 15:51:14 INFO - 'tests': ['damp', 'tps']}, 15:51:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:51:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:51:14 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:51:14 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:51:14 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:51:14 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:51:14 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:51:14 INFO - 'talos_options': ['--enable-stylo'], 15:51:14 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:51:14 INFO - 'other-e10s': {'tests': ['a11yr', 15:51:14 INFO - 'ts_paint', 15:51:14 INFO - 'tpaint', 15:51:14 INFO - 'sessionrestore', 15:51:14 INFO - 'sessionrestore_many_windows', 15:51:14 INFO - 'sessionrestore_no_auto_restore', 15:51:14 INFO - 'tabpaint']}, 15:51:14 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:51:14 INFO - 'tests': ['a11yr', 15:51:14 INFO - 'ts_paint', 15:51:14 INFO - 'tpaint', 15:51:14 INFO - 'sessionrestore', 15:51:14 INFO - 'sessionrestore_many_windows', 15:51:14 INFO - 'sessionrestore_no_auto_restore', 15:51:14 INFO - 'tabpaint']}, 15:51:14 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:51:14 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:51:14 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:51:14 INFO - 'tests': ['bloom_basic_singleton']}, 15:51:14 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:51:14 INFO - 'tests': ['bloom_basic']}, 15:51:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:51:14 INFO - 'tsvgr_opacity', 15:51:14 INFO - 'tart', 15:51:14 INFO - 'tscrollx', 15:51:14 INFO - 'tsvg_static']}, 15:51:14 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:51:14 INFO - 'tests': ['tsvgx', 15:51:14 INFO - 'tsvgr_opacity', 15:51:14 INFO - 'tart', 15:51:14 INFO - 'tscrollx', 15:51:14 INFO - 'tsvg_static']}, 15:51:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:51:14 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:51:14 INFO - 'talos_options': ['--enable-stylo'], 15:51:14 INFO - 'tests': ['tp5o']}, 15:51:14 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:51:14 INFO - 'talos_options': ['--mitmproxy', 15:51:14 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:51:14 INFO - '--firstNonBlankPaint'], 15:51:14 INFO - 'tests': ['tp6_google', 15:51:14 INFO - 'tp6_youtube', 15:51:14 INFO - 'tp6_amazon', 15:51:14 INFO - 'tp6_facebook']}, 15:51:14 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:51:14 INFO - 'talos_options': ['--enable-stylo', 15:51:14 INFO - '--mitmproxy', 15:51:14 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:51:14 INFO - '--firstNonBlankPaint'], 15:51:14 INFO - 'tests': ['tp6_google', 15:51:14 INFO - 'tp6_youtube', 15:51:14 INFO - 'tp6_amazon', 15:51:14 INFO - 'tp6_facebook']}, 15:51:14 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:51:14 INFO - 'talos_options': ['--enable-stylo', 15:51:14 INFO - '--stylo-threads=1', 15:51:14 INFO - '--mitmproxy', 15:51:14 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:51:14 INFO - '--firstNonBlankPaint'], 15:51:14 INFO - 'tests': ['tp6_google', 15:51:14 INFO - 'tp6_youtube', 15:51:14 INFO - 'tp6_amazon', 15:51:14 INFO - 'tp6_facebook']}, 15:51:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:51:14 INFO - 'talos_options': ['--xperf_path', 15:51:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:51:14 INFO - 'tests': ['tp5n']}, 15:51:14 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:51:14 INFO - 'talos_options': ['--enable-stylo', 15:51:14 INFO - '--xperf_path', 15:51:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:51:14 INFO - 'tests': ['tp5n']}}} 15:51:14 INFO - Running post-action listener: _resource_record_post_action 15:51:14 INFO - [mozharness: 2017-09-02 22:51:14.678958Z] Finished populate-webroot step (success) 15:51:14 INFO - [mozharness: 2017-09-02 22:51:14.679047Z] Running create-virtualenv step. 15:51:14 INFO - Running pre-action listener: _resource_record_pre_action 15:51:14 INFO - Running main action method: create_virtualenv 15:51:14 INFO - Creating virtualenv /builds/slave/test/build/venv 15:51:14 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:51:14 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:51:14 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:51:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:51:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:51:15 INFO - Installing distribute.............................................................................................................................................................................................done. 15:51:15 INFO - Installing pip...............done. 15:51:15 INFO - Return code: 0 15:51:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:51:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:51:15 INFO - Reading from file tmpfile_stdout 15:51:15 INFO - Output received: 15:51:15 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:51:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:51:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf55a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc090>, '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 0x10fcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6f90>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:51:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:51:15 INFO - Using env: {'DISPLAY': ':0', 15:51:15 INFO - 'HOME': '/home/cltbld', 15:51:15 INFO - 'LANG': 'en_US.UTF-8', 15:51:15 INFO - 'LANGUAGE': 'en_US:en', 15:51:15 INFO - 'LOGNAME': 'cltbld', 15:51:15 INFO - 'MAIL': '/var/mail/cltbld', 15:51:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:15 INFO - 'MOZ_NO_REMOTE': '1', 15:51:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:15 INFO - 'NO_EM_RESTART': '1', 15:51:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:15 INFO - 'PWD': '/builds/slave/test', 15:51:15 INFO - 'SHELL': '/bin/bash', 15:51:15 INFO - 'SHLVL': '1', 15:51:15 INFO - 'TERM': 'linux', 15:51:15 INFO - 'TMOUT': '86400', 15:51:15 INFO - 'USER': 'cltbld', 15:51:15 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 15:51:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:15 INFO - '_': '/tools/buildbot/bin/python'} 15:51:16 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:51:16 INFO - Downloading/unpacking pip>=1.5 15:51:16 INFO - Running setup.py egg_info for package pip 15:51:16 INFO - warning: no files found matching 'pip/cacert.pem' 15:51:16 INFO - warning: no files found matching '*.html' under directory 'docs' 15:51:16 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:51:16 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:51:16 INFO - Installing collected packages: pip 15:51:16 INFO - Found existing installation: pip 1.1 15:51:16 INFO - Uninstalling pip: 15:51:16 INFO - Successfully uninstalled pip 15:51:16 INFO - Running setup.py install for pip 15:51:16 INFO - warning: no files found matching 'pip/cacert.pem' 15:51:16 INFO - warning: no files found matching '*.html' under directory 'docs' 15:51:16 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:51:16 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:51:16 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:51:16 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:51:16 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:51:16 INFO - Successfully installed pip 15:51:16 INFO - Cleaning up... 15:51:16 INFO - Return code: 0 15:51:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:51:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:16 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 0xd8c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf55a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc090>, '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 0x10fcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6f90>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:51:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:51:16 INFO - Using env: {'DISPLAY': ':0', 15:51:16 INFO - 'HOME': '/home/cltbld', 15:51:16 INFO - 'LANG': 'en_US.UTF-8', 15:51:16 INFO - 'LANGUAGE': 'en_US:en', 15:51:16 INFO - 'LOGNAME': 'cltbld', 15:51:16 INFO - 'MAIL': '/var/mail/cltbld', 15:51:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:16 INFO - 'MOZ_NO_REMOTE': '1', 15:51:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:16 INFO - 'NO_EM_RESTART': '1', 15:51:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:16 INFO - 'PWD': '/builds/slave/test', 15:51:16 INFO - 'SHELL': '/bin/bash', 15:51:16 INFO - 'SHLVL': '1', 15:51:16 INFO - 'TERM': 'linux', 15:51:16 INFO - 'TMOUT': '86400', 15:51:16 INFO - 'USER': 'cltbld', 15:51:16 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 15:51:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:16 INFO - '_': '/tools/buildbot/bin/python'} 15:51:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:17 INFO - Downloading/unpacking psutil>=3.1.1 15:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:51:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:51:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:51:18 INFO - Installing collected packages: psutil 15:51:18 INFO - Running setup.py install for psutil 15:51:18 INFO - building 'psutil._psutil_linux' extension 15:51:18 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:51:18 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:51:18 INFO - building 'psutil._psutil_posix' extension 15:51:18 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:51:18 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:51:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:51:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:51:18 INFO - Successfully installed psutil 15:51:18 INFO - Cleaning up... 15:51:18 INFO - Return code: 0 15:51:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:51:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:18 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 0xd8c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf55a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc090>, '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 0x10fcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6f90>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:51:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:51:18 INFO - Using env: {'DISPLAY': ':0', 15:51:18 INFO - 'HOME': '/home/cltbld', 15:51:18 INFO - 'LANG': 'en_US.UTF-8', 15:51:18 INFO - 'LANGUAGE': 'en_US:en', 15:51:18 INFO - 'LOGNAME': 'cltbld', 15:51:18 INFO - 'MAIL': '/var/mail/cltbld', 15:51:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:18 INFO - 'MOZ_NO_REMOTE': '1', 15:51:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:18 INFO - 'NO_EM_RESTART': '1', 15:51:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:18 INFO - 'PWD': '/builds/slave/test', 15:51:18 INFO - 'SHELL': '/bin/bash', 15:51:18 INFO - 'SHLVL': '1', 15:51:18 INFO - 'TERM': 'linux', 15:51:18 INFO - 'TMOUT': '86400', 15:51:18 INFO - 'USER': 'cltbld', 15:51:18 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 15:51:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:18 INFO - '_': '/tools/buildbot/bin/python'} 15:51:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:51:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:51:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:51:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:51:20 INFO - Installing collected packages: mozsystemmonitor 15:51:20 INFO - Running setup.py install for mozsystemmonitor 15:51:20 INFO - Successfully installed mozsystemmonitor 15:51:20 INFO - Cleaning up... 15:51:20 INFO - Return code: 0 15:51:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:51:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:20 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 0xd8c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf55a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc090>, '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 0x10fcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6f90>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:51:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:51:20 INFO - Using env: {'DISPLAY': ':0', 15:51:20 INFO - 'HOME': '/home/cltbld', 15:51:20 INFO - 'LANG': 'en_US.UTF-8', 15:51:20 INFO - 'LANGUAGE': 'en_US:en', 15:51:20 INFO - 'LOGNAME': 'cltbld', 15:51:20 INFO - 'MAIL': '/var/mail/cltbld', 15:51:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:20 INFO - 'MOZ_NO_REMOTE': '1', 15:51:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:20 INFO - 'NO_EM_RESTART': '1', 15:51:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:20 INFO - 'PWD': '/builds/slave/test', 15:51:20 INFO - 'SHELL': '/bin/bash', 15:51:20 INFO - 'SHLVL': '1', 15:51:20 INFO - 'TERM': 'linux', 15:51:20 INFO - 'TMOUT': '86400', 15:51:20 INFO - 'USER': 'cltbld', 15:51:20 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 15:51:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:20 INFO - '_': '/tools/buildbot/bin/python'} 15:51:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:20 INFO - Downloading/unpacking jsonschema==2.5.1 15:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:51:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:21 INFO - Downloading functools32-3.2.3-2.tar.gz 15:51:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:51:21 INFO - warning: no files found matching '*.txt' 15:51:21 INFO - no previously-included directories found matching 'build' 15:51:21 INFO - no previously-included directories found matching 'dist' 15:51:21 INFO - no previously-included directories found matching '.git*' 15:51:21 INFO - Installing collected packages: jsonschema, functools32 15:51:21 INFO - Running setup.py install for functools32 15:51:21 INFO - warning: no files found matching '*.txt' 15:51:21 INFO - no previously-included directories found matching 'build' 15:51:21 INFO - no previously-included directories found matching 'dist' 15:51:21 INFO - no previously-included directories found matching '.git*' 15:51:21 INFO - Successfully installed jsonschema functools32 15:51:21 INFO - Cleaning up... 15:51:21 INFO - Return code: 0 15:51:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:51:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:22 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 0xd8c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf55a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc090>, '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 0x10fcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6f90>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:51:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:51:22 INFO - Using env: {'DISPLAY': ':0', 15:51:22 INFO - 'HOME': '/home/cltbld', 15:51:22 INFO - 'LANG': 'en_US.UTF-8', 15:51:22 INFO - 'LANGUAGE': 'en_US:en', 15:51:22 INFO - 'LOGNAME': 'cltbld', 15:51:22 INFO - 'MAIL': '/var/mail/cltbld', 15:51:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:22 INFO - 'MOZ_NO_REMOTE': '1', 15:51:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:22 INFO - 'NO_EM_RESTART': '1', 15:51:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:22 INFO - 'PWD': '/builds/slave/test', 15:51:22 INFO - 'SHELL': '/bin/bash', 15:51:22 INFO - 'SHLVL': '1', 15:51:22 INFO - 'TERM': 'linux', 15:51:22 INFO - 'TMOUT': '86400', 15:51:22 INFO - 'USER': 'cltbld', 15:51:22 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 15:51:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:22 INFO - '_': '/tools/buildbot/bin/python'} 15:51:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:51:22 INFO - Cleaning up... 15:51:22 INFO - Return code: 0 15:51:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:51:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:22 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 0xd8c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf55a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc090>, '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 0x10fcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6f90>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:51:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:51:22 INFO - Using env: {'DISPLAY': ':0', 15:51:22 INFO - 'HOME': '/home/cltbld', 15:51:22 INFO - 'LANG': 'en_US.UTF-8', 15:51:22 INFO - 'LANGUAGE': 'en_US:en', 15:51:22 INFO - 'LOGNAME': 'cltbld', 15:51:22 INFO - 'MAIL': '/var/mail/cltbld', 15:51:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:22 INFO - 'MOZ_NO_REMOTE': '1', 15:51:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:22 INFO - 'NO_EM_RESTART': '1', 15:51:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:22 INFO - 'PWD': '/builds/slave/test', 15:51:22 INFO - 'SHELL': '/bin/bash', 15:51:22 INFO - 'SHLVL': '1', 15:51:22 INFO - 'TERM': 'linux', 15:51:22 INFO - 'TMOUT': '86400', 15:51:22 INFO - 'USER': 'cltbld', 15:51:22 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 15:51:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:22 INFO - '_': '/tools/buildbot/bin/python'} 15:51:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:22 INFO - Downloading/unpacking blobuploader==1.2.4 15:51:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:23 INFO - Downloading blobuploader-1.2.4.tar.gz 15:51:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:51:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:51:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:51:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:51:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:24 INFO - Downloading docopt-0.6.1.tar.gz 15:51:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:51:24 INFO - Installing collected packages: blobuploader, requests, docopt 15:51:24 INFO - Running setup.py install for blobuploader 15:51:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:51:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:51:24 INFO - Running setup.py install for requests 15:51:24 INFO - Running setup.py install for docopt 15:51:24 INFO - Successfully installed blobuploader requests docopt 15:51:24 INFO - Cleaning up... 15:51:24 INFO - Return code: 0 15:51:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:24 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 0xd8c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf55a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc090>, '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 0x10fcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6f90>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:51:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:51:24 INFO - Using env: {'DISPLAY': ':0', 15:51:24 INFO - 'HOME': '/home/cltbld', 15:51:24 INFO - 'LANG': 'en_US.UTF-8', 15:51:24 INFO - 'LANGUAGE': 'en_US:en', 15:51:24 INFO - 'LOGNAME': 'cltbld', 15:51:24 INFO - 'MAIL': '/var/mail/cltbld', 15:51:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:24 INFO - 'MOZ_NO_REMOTE': '1', 15:51:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:24 INFO - 'NO_EM_RESTART': '1', 15:51:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:24 INFO - 'PWD': '/builds/slave/test', 15:51:24 INFO - 'SHELL': '/bin/bash', 15:51:24 INFO - 'SHLVL': '1', 15:51:24 INFO - 'TERM': 'linux', 15:51:24 INFO - 'TMOUT': '86400', 15:51:24 INFO - 'USER': 'cltbld', 15:51:24 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 15:51:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:24 INFO - '_': '/tools/buildbot/bin/python'} 15:51:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:51:24 INFO - Running setup.py (path:/tmp/pip-w3b7CX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:51:25 INFO - Running setup.py (path:/tmp/pip-hh3w4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:51:25 INFO - Running setup.py (path:/tmp/pip-G2HYI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:51:25 INFO - Running setup.py (path:/tmp/pip-GoDOuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:51:25 INFO - Running setup.py (path:/tmp/pip-mYpa8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:51:25 INFO - Running setup.py (path:/tmp/pip-BJ6a2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:51:25 INFO - Running setup.py (path:/tmp/pip-3MRIoF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:51:25 INFO - Running setup.py (path:/tmp/pip-Zry_ka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:51:25 INFO - Running setup.py (path:/tmp/pip-emmRqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:51:25 INFO - Running setup.py (path:/tmp/pip-zckw8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:51:25 INFO - Running setup.py (path:/tmp/pip-4ACc8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:51:25 INFO - Running setup.py (path:/tmp/pip-jiecT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:51:25 INFO - Running setup.py (path:/tmp/pip-LAHGbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:51:25 INFO - Running setup.py (path:/tmp/pip-qLoltn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:25 INFO - Running setup.py (path:/tmp/pip-2PireQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:51:26 INFO - Running setup.py (path:/tmp/pip-sBJKO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:51:26 INFO - Running setup.py (path:/tmp/pip-ok5MI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:51:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:51:26 INFO - Running setup.py install for manifestparser 15:51:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:51:26 INFO - Running setup.py install for mozcrash 15:51:26 INFO - Running setup.py install for mozdebug 15:51:26 INFO - Running setup.py install for mozdevice 15:51:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:51:26 INFO - Running setup.py install for mozfile 15:51:26 INFO - Running setup.py install for mozhttpd 15:51:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:51:26 INFO - Running setup.py install for mozinfo 15:51:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:51:26 INFO - Running setup.py install for mozInstall 15:51:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:51:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:51:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:51:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:51:26 INFO - Running setup.py install for mozleak 15:51:27 INFO - Running setup.py install for mozlog 15:51:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:51:27 INFO - Running setup.py install for moznetwork 15:51:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:51:27 INFO - Running setup.py install for mozprocess 15:51:27 INFO - Running setup.py install for mozprofile 15:51:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:51:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:51:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:51:27 INFO - Running setup.py install for mozrunner 15:51:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:51:27 INFO - Running setup.py install for mozscreenshot 15:51:27 INFO - Running setup.py install for moztest 15:51:27 INFO - Running setup.py install for mozversion 15:51:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:51:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:51:27 INFO - Cleaning up... 15:51:27 INFO - Return code: 0 15:51:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:27 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 0xd8c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf55a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc090>, '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 0x10fcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6f90>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:51:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:51:27 INFO - Using env: {'DISPLAY': ':0', 15:51:27 INFO - 'HOME': '/home/cltbld', 15:51:27 INFO - 'LANG': 'en_US.UTF-8', 15:51:27 INFO - 'LANGUAGE': 'en_US:en', 15:51:27 INFO - 'LOGNAME': 'cltbld', 15:51:27 INFO - 'MAIL': '/var/mail/cltbld', 15:51:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:27 INFO - 'MOZ_NO_REMOTE': '1', 15:51:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:27 INFO - 'NO_EM_RESTART': '1', 15:51:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:27 INFO - 'PWD': '/builds/slave/test', 15:51:27 INFO - 'SHELL': '/bin/bash', 15:51:27 INFO - 'SHLVL': '1', 15:51:27 INFO - 'TERM': 'linux', 15:51:27 INFO - 'TMOUT': '86400', 15:51:27 INFO - 'USER': 'cltbld', 15:51:27 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 15:51:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:27 INFO - '_': '/tools/buildbot/bin/python'} 15:51:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:51:28 INFO - Running setup.py (path:/tmp/pip-OsUgYC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:51:28 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:51:28 INFO - Running setup.py (path:/tmp/pip-3eAH05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:51:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:51:28 INFO - Running setup.py (path:/tmp/pip-dQj4eC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:51:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:51:28 INFO - Running setup.py (path:/tmp/pip-CDsYXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:51:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:51:28 INFO - Running setup.py (path:/tmp/pip-0VBDjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:51:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:51:28 INFO - Running setup.py (path:/tmp/pip-0ncv03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:51:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:51:28 INFO - Running setup.py (path:/tmp/pip-8KHxhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:51:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:51:28 INFO - Running setup.py (path:/tmp/pip-WKpXku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:51:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:51:28 INFO - Running setup.py (path:/tmp/pip-MSTFE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:51:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:51:28 INFO - Running setup.py (path:/tmp/pip-GpiZsf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:51:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:51:28 INFO - Running setup.py (path:/tmp/pip-i0Y22P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:51:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:51:28 INFO - Running setup.py (path:/tmp/pip-Szjv8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:51:29 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)) 15:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:51:29 INFO - Running setup.py (path:/tmp/pip-tX3Cby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:51:29 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)) 15:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:51:29 INFO - Running setup.py (path:/tmp/pip-K5zAik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:51:29 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)) 15:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:29 INFO - Running setup.py (path:/tmp/pip-BBAlI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:29 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)) 15:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:51:29 INFO - Running setup.py (path:/tmp/pip-xPcdEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:51:29 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)) 15:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:51:29 INFO - Running setup.py (path:/tmp/pip-S09Uha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:51:29 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)) 15:51:29 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)) 15:51:29 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)) 15:51:29 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)) 15:51:29 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)) 15:51:29 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)) 15:51:29 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)) 15:51:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:51: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 15:51: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 15:51: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 15:51: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 15:51:30 INFO - Downloading blessings-1.6.tar.gz 15:51:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:51:30 INFO - Installing collected packages: blessings 15:51:30 INFO - Running setup.py install for blessings 15:51:30 INFO - Successfully installed blessings 15:51:30 INFO - Cleaning up... 15:51:30 INFO - Return code: 0 15:51:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:51:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:51:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:51:30 INFO - Reading from file tmpfile_stdout 15:51:30 INFO - Current package versions: 15:51:30 INFO - argparse == 1.2.1 15:51:30 INFO - blessings == 1.6 15:51:30 INFO - blobuploader == 1.2.4 15:51:30 INFO - docopt == 0.6.1 15:51:30 INFO - functools32 == 3.2.3-2 15:51:30 INFO - jsonschema == 2.5.1 15:51:30 INFO - manifestparser == 1.1 15:51:30 INFO - mozInstall == 1.13 15:51:30 INFO - mozcrash == 1.0 15:51:30 INFO - mozdebug == 0.1 15:51:30 INFO - mozdevice == 0.51 15:51:30 INFO - mozfile == 1.2 15:51:30 INFO - mozhttpd == 0.7 15:51:30 INFO - mozinfo == 0.10 15:51:30 INFO - mozleak == 0.1 15:51:30 INFO - mozlog == 3.5 15:51:30 INFO - moznetwork == 0.27 15:51:30 INFO - mozprocess == 0.25 15:51:30 INFO - mozprofile == 0.28 15:51:30 INFO - mozrunner == 6.13 15:51:30 INFO - mozscreenshot == 0.1 15:51:30 INFO - mozsystemmonitor == 0.3 15:51:30 INFO - moztest == 0.8 15:51:30 INFO - mozversion == 1.4 15:51:30 INFO - psutil == 3.1.1 15:51:30 INFO - requests == 1.2.3 15:51:30 INFO - wsgiref == 0.1.2 15:51:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:30 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 0xd8c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf55a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc090>, '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 0x10fcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6f90>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:30 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 15:51:30 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 15:51:30 INFO - Using env: {'DISPLAY': ':0', 15:51:30 INFO - 'HOME': '/home/cltbld', 15:51:30 INFO - 'LANG': 'en_US.UTF-8', 15:51:30 INFO - 'LANGUAGE': 'en_US:en', 15:51:30 INFO - 'LOGNAME': 'cltbld', 15:51:30 INFO - 'MAIL': '/var/mail/cltbld', 15:51:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:30 INFO - 'MOZ_NO_REMOTE': '1', 15:51:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:30 INFO - 'NO_EM_RESTART': '1', 15:51:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:30 INFO - 'PWD': '/builds/slave/test', 15:51:30 INFO - 'SHELL': '/bin/bash', 15:51:30 INFO - 'SHLVL': '1', 15:51:30 INFO - 'TERM': 'linux', 15:51:30 INFO - 'TMOUT': '86400', 15:51:30 INFO - 'USER': 'cltbld', 15:51:30 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 15:51:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:30 INFO - '_': '/tools/buildbot/bin/python'} 15:51:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:31 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)) 15:51:31 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)) 15:51:31 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)) 15:51:31 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)) 15:51:31 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)) 15:51:31 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)) 15:51:31 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)) 15:51:31 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)) 15:51:31 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)) 15:51:31 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:51: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 15:51: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 15:51: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 15:51: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 15:51:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:51:32 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)) 15:51:32 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)) 15:51:32 INFO - Installing collected packages: simplejson 15:51:32 INFO - Running setup.py install for simplejson 15:51:32 INFO - building 'simplejson._speedups' extension 15:51:32 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 15:51:33 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 15:51:33 INFO - Successfully installed simplejson 15:51:33 INFO - Cleaning up... 15:51:33 INFO - Return code: 0 15:51:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:51: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')]} 15:51:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51: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')]} 15:51:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf55a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc090>, '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 0x10fcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6f90>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51: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'] in /builds/slave/test/build 15:51: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 15:51:33 INFO - Using env: {'DISPLAY': ':0', 15:51:33 INFO - 'HOME': '/home/cltbld', 15:51:33 INFO - 'LANG': 'en_US.UTF-8', 15:51:33 INFO - 'LANGUAGE': 'en_US:en', 15:51:33 INFO - 'LOGNAME': 'cltbld', 15:51:33 INFO - 'MAIL': '/var/mail/cltbld', 15:51:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:33 INFO - 'MOZ_NO_REMOTE': '1', 15:51:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:33 INFO - 'NO_EM_RESTART': '1', 15:51:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:33 INFO - 'PWD': '/builds/slave/test', 15:51:33 INFO - 'SHELL': '/bin/bash', 15:51:33 INFO - 'SHLVL': '1', 15:51:33 INFO - 'TERM': 'linux', 15:51:33 INFO - 'TMOUT': '86400', 15:51:33 INFO - 'USER': 'cltbld', 15:51:33 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 15:51:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:33 INFO - '_': '/tools/buildbot/bin/python'} 15:51:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:51:33 INFO - Cleaning up... 15:51:33 INFO - Return code: 0 15:51:33 INFO - Running post-action listener: _resource_record_post_action 15:51:33 INFO - Running post-action listener: _start_resource_monitoring 15:51:33 INFO - Starting resource monitoring. 15:51:33 INFO - [mozharness: 2017-09-02 22:51:33.639103Z] Finished create-virtualenv step (success) 15:51:33 INFO - [mozharness: 2017-09-02 22:51:33.639699Z] Running install step. 15:51:33 INFO - Running pre-action listener: _resource_record_pre_action 15:51:33 INFO - Running main action method: install 15:51:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:51:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:51:33 INFO - Reading from file tmpfile_stdout 15:51:33 INFO - Detecting whether we're running mozinstall >=1.0... 15:51:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:51:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:51:34 INFO - Reading from file tmpfile_stdout 15:51:34 INFO - Output received: 15:51:34 INFO - Usage: mozinstall [options] installer 15:51:34 INFO - Options: 15:51:34 INFO - -h, --help show this help message and exit 15:51:34 INFO - -d DEST, --destination=DEST 15:51:34 INFO - Directory to install application into. [default: 15:51:34 INFO - "/builds/slave/test"] 15:51:34 INFO - --app=APP Application being installed. [default: firefox] 15:51:34 INFO - mkdir: /builds/slave/test/build/application 15:51:34 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'] 15:51:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:51:50 INFO - Reading from file tmpfile_stdout 15:51:50 INFO - Output received: 15:51:50 INFO - /builds/slave/test/build/application/firefox/firefox 15:51:50 INFO - Running post-action listener: _resource_record_post_action 15:51:50 INFO - [mozharness: 2017-09-02 22:51:50.497411Z] Finished install step (success) 15:51:50 INFO - [mozharness: 2017-09-02 22:51:50.497661Z] Running setup-mitmproxy step. 15:51:50 INFO - Running pre-action listener: _resource_record_pre_action 15:51:50 INFO - Running main action method: setup_mitmproxy 15:51:50 INFO - Skipping: mitmproxy is not required 15:51:50 INFO - Running post-action listener: _resource_record_post_action 15:51:50 INFO - [mozharness: 2017-09-02 22:51:50.498777Z] Finished setup-mitmproxy step (success) 15:51:50 INFO - [mozharness: 2017-09-02 22:51:50.498995Z] Running run-tests step. 15:51:50 INFO - Running pre-action listener: _resource_record_pre_action 15:51:50 INFO - Running pre-action listener: _set_gcov_prefix 15:51:50 INFO - Running main action method: run_tests 15:51:50 WARNING - Try message not found. 15:51:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:51:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:51:50 INFO - Python 2.7.3 15:51:50 INFO - Return code: 0 15:51:50 INFO - grabbing minidump binary from tooltool 15:51:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:50 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 0x10fc090>, '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 0x10fcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:51:50 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 15:51:50 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 15:51:50 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 15:51:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:51:50 INFO - Return code: 0 15:51:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:51:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:51:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:51:50 INFO - ENV: RUST_BACKTRACE is now full 15:51:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:51:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:51:50 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/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 15:51:50 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/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-062 --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 15:51:50 INFO - Using env: {'DISPLAY': ':0', 15:51:50 INFO - 'HOME': '/home/cltbld', 15:51:50 INFO - 'LANG': 'en_US.UTF-8', 15:51:50 INFO - 'LANGUAGE': 'en_US:en', 15:51:50 INFO - 'LOGNAME': 'cltbld', 15:51:50 INFO - 'MAIL': '/var/mail/cltbld', 15:51:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:51:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:51:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:50 INFO - 'MOZ_NO_REMOTE': '1', 15:51:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:51:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:50 INFO - 'NO_EM_RESTART': '1', 15:51:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:50 INFO - 'PWD': '/builds/slave/test', 15:51:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:51:50 INFO - 'RUST_BACKTRACE': 'full', 15:51:50 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:51:50 INFO - 'SHELL': '/bin/bash', 15:51:50 INFO - 'SHLVL': '1', 15:51:50 INFO - 'STYLO_FORCE_ENABLED': '1', 15:51:50 INFO - 'TERM': 'linux', 15:51:50 INFO - 'TMOUT': '86400', 15:51:50 INFO - 'USER': 'cltbld', 15:51:50 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 15:51:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:50 INFO - '_': '/tools/buildbot/bin/python'} 15:51:50 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/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 15:51:50 INFO - mozversion application_buildid: 20170902215941 15:51:50 INFO - mozversion application_changeset: 39e9a6b187a3f71f46d02af000ff8203abe4fe71 15:51:50 INFO - mozversion application_display_name: Nightly 15:51:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:51:50 INFO - mozversion application_name: Firefox 15:51:50 INFO - mozversion application_remotingname: firefox 15:51:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:51:50 INFO - mozversion application_vendor: Mozilla 15:51:50 INFO - mozversion application_version: 57.0a1 15:51:50 INFO - mozversion platform_buildid: 20170902215941 15:51:50 INFO - mozversion platform_changeset: 39e9a6b187a3f71f46d02af000ff8203abe4fe71 15:51:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:51:50 INFO - mozversion platform_version: 57.0a1 15:51:50 INFO - using testdate: 1504392710 15:51:50 INFO - actual date: 1504392710 15:51:50 INFO - starting webserver on 'localhost:36701' 15:51:50 INFO - SUITE-START | Running 2 tests 15:51:50 INFO - TEST-START | dromaeo_css 15:51:50 INFO - Initialising browser for dromaeo_css test... 15:51:50 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36701/getInfo.html -profile /tmp/tmpJD_uEK/profile 15:51:50 INFO - TEST-INFO | started process 14111 (/builds/slave/test/build/application/firefox/firefox http://localhost:36701/getInfo.html) 15:51:56 INFO - TEST-INFO | 14111: exit 0 15:51:56 INFO - Browser initialized. 15:51:56 INFO - Running cycle 1/1 for dromaeo_css test... 15:51:56 INFO - Using env: {'DISPLAY': ':0', 15:51:56 INFO - 'HOME': '/home/cltbld', 15:51:56 INFO - 'JSGC_DISABLE_POISONING': '1', 15:51:56 INFO - 'LANG': 'en_US.UTF-8', 15:51:56 INFO - 'LANGUAGE': 'en_US:en', 15:51:56 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:51:56 INFO - 'LOGNAME': 'cltbld', 15:51:56 INFO - 'MAIL': '/var/mail/cltbld', 15:51:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:51:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:51:56 INFO - 'MOZ_CRASHREPORTER': '1', 15:51:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:56 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:51:56 INFO - 'MOZ_NO_REMOTE': '1', 15:51:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:51:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:56 INFO - 'NO_EM_RESTART': '1', 15:51:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:56 INFO - 'PWD': '/builds/slave/test', 15:51:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:51:56 INFO - 'RUST_BACKTRACE': 'full', 15:51:56 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:51:56 INFO - 'SHELL': '/bin/bash', 15:51:56 INFO - 'SHLVL': '1', 15:51:56 INFO - 'STYLO_FORCE_ENABLED': '1', 15:51:56 INFO - 'TERM': 'linux', 15:51:56 INFO - 'TMOUT': '86400', 15:51:56 INFO - 'USER': 'cltbld', 15:51:56 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 15:51:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:56 INFO - '_': '/tools/buildbot/bin/python'} 15:51:56 INFO - TEST-INFO | started process 14309 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJD_uEK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:51:57 INFO - PID 14309 | 15:51:57 INFO - PID 14309 | (/builds/slave/test/build/application/firefox/firefox:14361): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:51:57 INFO - PID 14309 | 15:52:08 INFO - PID 14309 | RSS: Main: 170696704 15:52:08 INFO - PID 14309 | 15:55:07 INFO - PID 14309 | Cycle 1(1): loaded http://localhost:36701/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36701/tests/dromaeo/cssquery-ext.html) 15:55:08 INFO - PID 14309 | RSS: Main: 173580288 15:55:08 INFO - PID 14309 | 15:58:10 INFO - PID 14309 | Cycle 1(1): loaded http://localhost:36701/tests/dromaeo/cssquery-ext.html (next: http://localhost:36701/tests/dromaeo/cssquery-jquery.html) 15:58:10 INFO - PID 14309 | RSS: Main: 170627072 15:58:10 INFO - PID 14309 | 16:01:12 INFO - PID 14309 | Cycle 1(1): loaded http://localhost:36701/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36701/tests/dromaeo/cssquery-mootools.html) 16:01:13 INFO - PID 14309 | RSS: Main: 173125632 16:01:13 INFO - PID 14309 | 16:04:14 INFO - PID 14309 | Cycle 1(1): loaded http://localhost:36701/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36701/tests/dromaeo/cssquery-prototype.html) 16:04:15 INFO - PID 14309 | RSS: Main: 171044864 16:04:15 INFO - PID 14309 | 16:07:19 INFO - PID 14309 | Cycle 1(1): loaded http://localhost:36701/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36701/tests/dromaeo/cssquery-yui.html) 16:07:19 INFO - PID 14309 | RSS: Main: 172797952 16:07:19 INFO - PID 14309 | 16:10:21 INFO - PID 14309 | Cycle 1(1): loaded http://localhost:36701/tests/dromaeo/cssquery-yui.html (next: http://localhost:36701/tests/dromaeo/cssquery-dojo.html) 16:10:21 INFO - PID 14309 | RSS: Main: 174882816 16:10:21 INFO - PID 14309 | 16:10:21 INFO - PID 14309 | __start_tp_report 16:10:21 INFO - PID 14309 | _x_x_mozilla_page_load 16:10:21 INFO - PID 14309 | _x_x_mozilla_page_load_details 16:10:21 INFO - PID 14309 | |i|pagename|runs| 16:10:21 INFO - PID 14309 | |0;dojo.html;3314.9502757458636;3318.2202182487113;3321.684439978201;3346.0631023313467;3397.813120278384;6372.776952806653;6502.609843409393;6519.250286217087;6554.508411869117;6570.934290657098;6369.363063693629;6436.452901503378;6450;6453.386928241821;6465.256495503018;5865.413458654122;5897.882042359161;5899.911501327483;5932.525397968152;5932.99139146344;5877.70611469427;5942.494887934531;5963.970180149093;6002.759889604411;6024.698765061751;5931.822045338647;5962.79130230442;6024.789132380368;6036.909446358308;6070.48400885923;5871.442212989759;5953.642781433085;5958.97020514899;5977.3723759005115;6077.483413909823;5680.971595142039;5757.136429535603;5817.88364232713;5865.002949498596;5909.2022576951995;6384.968075159641;6439.323870993572;6489.123968264271;6533.889238829411;6586.538942274043;6683.799486015428;6755.1556055493065;6802.285759995229;6840.931590684079;6906.965465172642;6700.430463410616;6757.797266082025;6825.897611535831;6841.973703944398;6901.378875901143;6362.172917520693;6392.137061496732;6435.388638079375;6439.452646525004;6440.066190402412;6949.30506949301;7069.8939515907305;7135;7156.320149585781;7160.462965277625;6706.295838936939;6754.797356079325;6792.184937807495;6823.965880170567;6868.793936181923;6801.387875091265;6850.143732033496;6869.141357330333;6885.380315771605;6928.237893831674;6838.247792742794;6854.794356169223;6858.794236172923;6920.377166055079;6923.169219693568;6864.141982252218;6941.271166527543;6969.303069693092;6975.720971161097;6977.720891164398;5417.702026388586;5449.3460784706085;5529.585281103853;5535.391106978229;5535.529479994165;4669.603083737852;4735.195016847144;4742.217534106902;4758.547937945889;4822.590079843182;5996.820095397145;6018.187544681499;6092.878142437126;6102.023676211785;6103.389661033861;7003;7007.684654190573;7064.046353742179;7078.469114816409;7085.114360704912;6097.481714054266;6104.272194413236;6123.38766123393;6190.473809726133;6198.969005154945;5833.125031245348;5900.64596124234;5934.109883517507;5973;5973.074173503114;6723.5293529452465;6779.559328643622;6780.220274668476;6782.626955517494;6785.185777706608;6822.761203357859;6892.379685828099;6994.5803251805055;7024.578525288499;7032.1912980007955;6575.704093315908;6648.468122550088;6699.698513566998;6750.35871592198;6794.116764820542;6839;6848.383645471931;6852.074969879002;6901.1718593769065;6919.619420931897;6969.75605853813;6979.930200697928;7004.929950700428;7077.292270772881;7099.574025558483;6679.632620205741;6705.564138330993;6755.7973260802255;6793.320667933365;6797.6941037654415;6530.8367290818105;6614.900776488453;6642.302558231316;6729.259781423942;6761.391474767294;6677.09859169006;6681;6685.0306705526455;6689.632070236184;6689.76585819494;6601.669916504252;6665.133532640726;6732.394084532416;6745.93254067453;6789.1174147360525;6409.679516024087;6439.227292724903;6444.033394990789;6447.516436267205;6457.257415397289 16:10:21 INFO - PID 14309 | |1;ext.html;794.9920500794994;841.8526757817382;872.2367928062945;878.7100256915219;1126.611319094912;29639.55540666889;30092.548611770842;38471.99999999996;39374.606253937454;39807.60192398075;40007.79996100023;40320.395194072036;40376.192476150536;40402.595974040254;40553.79723101381;7200.171980222289;7601.353884919775;7807.297343239107;7811.06267247931;7814.531128132302;7656.502327348732;7756;7757.534547927143;7769.339606133485;7777.494462859924;7759.495632783879;7817.374610031212;7823.178566250547;7825.530468171928;7832.843343133134;7738.961305193466;7750.922490775105;7765.728199513046;7776.688932442696;7793.376529877596;7668.194839541824;7670.117936437269;7732.613369331567;7740.303372696484;7753.147153813076;36994.63005369939;37080;37178.25643487115;37273;37289.62710372916;3508.0703613542432;3550.928981420357;3555.1289933966345;3557.9822100889587;3558.697510711567;30616.69383306183;30671;30822.075337739905;30883.69116308853;31033.224169395944;5047.394312682465;5133.998870220308;5135.229715542699;5151.6908985461;5178.197379406198;199507.99999999854;200138;200221;200229;200572;46124.76937615291;46470;46601;46623.30065049027;46984.530154698696;47809.52190478051;48060;48207.27689084667;48317;48680.756596216794;13966.162030278216;15646.374145034299;15748.76376854348;15763.68472630541;15777.921110394374;11692.532298708127;12766.29785361814;12992.090553661154;12997;13017;13539.729205415837;13624.795628065587;13663;13692.520761773489;13831.654208644864;5474.350526168644;5542.584306177053;5584.636998595079;5616.185653080281;5777.306723193244;984.6258421799813;1007.9143272821746;1009.2027298434155;1015.4059874973224;1035.746242170673;22631.773682262967;28025.57961630577;28055.71944280572;28303.15090547287;28681.713182868323;1078.6656136597524;1087.499750114956;1099.2689861242184;1099.8680158381046;1102.2559772153763;12159.209651372632;12289.69275768113;12342.568010119783;12378.257304561756;12394;236914;238998;239566;241408;241759;48627.75686121618;49296;49331.75334123307;49334.25998610024;49397.50602493929;1402.144691738039;1428.178797191619;1440.6038339456734;1444.3428240150706;1452.0416525093387;21988.34034978953;21999.560008799413;22044.88977555102;22231.888840555694;22464.213752518583;47723;49282.26076608925;49397.753011234716;49480.50519494759;49514.25728614146;204538;205069;205751;206178;206387;30708.385832283675;31098.844505777328;33784.3243135131;34027.829860850536;34063.48904766381;41633.79183104065;41856.3721544171;41898.37152442655;42110.157796844505;42145.78927105345;30936.381272374878;31080.844595776878;31112.22219444532;31544.36911261808;31716.36567268688;30236.69763302427;30256.394872102876;30414.54378184373;30540.389192216477;30799.538006929466;34596.30807383889;34685.65314346824;34747.30505389928;34767.47848782321;34846.12884677904;29555.408891821615;29815.552766708082;29877;29901.850490747405;29987;22406.88796556007;22489.32532024042;24195.274141775775;24323.63514547248;24443.877780610983 16:10:21 INFO - PID 14309 | |2;jquery.html;564.9096144616859;566.1988286574498;569.4647031790114;574.9281339832521;589.716935870782;21477.99999999998;22352.77647223529;22380.776192238074;22389.328320150402;23810.5237895242;21157.68263476049;21497.355079347646;21500;21558.461038474044;21782.89108554455;6508.576942498746;6684.766033188839;6738.494612904052;6759.459243260528;6767.49243806714;5960.910586341209;6441.227052753653;6463.48292136628;6501.4798816094835;6564.04821300911;6424.486041116723;6432.453241474457;6432.5497215194955;6433.7104830282515;6509.316521765218;6416.582922110095;6450.322716114834;6508.121403610499;6515.283318834924;6519.706613202417;6040.365761595057;6046.546509011798;6046.667433291167;6052.152698622196;6073.210482637273;27520.44959100827;28021.299467513272;28203.15390538287;28528.28679283014;29059.273518162005;2060.721802556651;2090.843186760999;2092.0376626751713;2093.371988403488;2122.0662908320446;18795.53011174718;19457.318993835146;19557.413277601696;19565.413037608894;19718.507037324038;2281.361218858722;2307.734610519778;2361.1027809432476;2362.173239366225;2362.9291121266388;134712;140000;140366;141186.29406853003;141226.29386853;13525.864741352461;14318.785218221736;14469.565913022627;14530.564083077525;14772.261386930826;22392.664110038364;22727.204547840745;22889.427764306027;23322.76677233206;23344.53310933806;9475.715728528156;9544.141027307436;9639.228861691188;9650.806983860424;9711.757206069904;8739.56302184901;8832;9413.90586094144;9436;9489.430634161972;8938.932785582545;8979.10208979097;8991.14584114508;9111.498867562215;9225.861612075823;2652.1465717736496;2652.53580623394;2659.5478768609382;2728.358835673615;2821.2523681224493;957.4351132831523;983.3657291047234;995.6117114325419;1002.2733518199393;1003.7440452684518;19245.615087698166;19578.70631940522;19608.724309425437;19723.50691232702;19892.204311827365;1035.7048241251207;1040.2406243442329;1044.6970378590124;1054.6097943760858;1054.6888667843004;10820.458977051116;10956.890431095588;10989.890101099047;11059.612913547988;11060.281081729661;455429;460424;462147;547742;553667;113903;115294;119366;120880;120889;1197.4910662968205;1230.7292395672937;1274.1335891593806;1283.223649691923;1284.216627857025;20531.38405847827;20664.380068597966;20749.58500830005;21179.152833886474;21356.7864321361;56059;57854.7107264461;57939.71030144822;58173;60337;67058.66470667615;67307;67421.32578674151;68238.3176168232;68508.3149168502;11993.76012479728;12008.159428839897;12640.87359126397;12871.485140594272;12888.226706397647;22623;22883.084676613416;22908.885455572614;22922.770772292064;22926;17575.384861529787;17868.821311787236;17878.19548120364;18396.908015459838;18628.627427451647;16346.509604711877;16587.419440319532;16709;17013.404530841362;17162.914185428992;18621.627567448846;18751.34370297033;18902.14940327661;18997.525061873563;19009.33467328637;14388.85611143917;14431.278436078364;14450.638734031734;14520.273986300854;14548.199849008406;18162.45512634623;18179.636407272046;18263.360782372554;19043.42869713911;19081.236750530377 16:10:21 INFO - PID 14309 | |3;mootools.html;1310.7902735562309;1374.0862326552838;1380.654836290928;1383.9238841863705;1389.4164450930612;14599.489017884376;15108.622284442894;15191.164485953268;15264.618384540392;15309.38762449502;14787.260636968136;15418.152001639904;15846.28691708873;15915.124668143277;16013.83986160138;2812.606235127084;2855.6144920435786;2858.6712528059334;2860.957085643717;2877.352595665979;2694.8113632045765;2760.875760590768;2818.027780415753;2818.9154325370273;2819.746222839954;2815.282103063716;2862.155664079102;2864.097809190109;2865.627468429101;2866.5700144978214;2714.0229517373828;2720.2383332666886;2749.4363655450584;2755.2836262571677;2759.4619049285297;2625.619285203654;2629.9868500657567;2650.045983445958;2652.350174207312;2659.8670066496793;14802.481913132988;14980.775288370683;14984.100953942798;15132.773008404884;15143.091414515164;7942.602869856484;8204.507729536188;8245.876311855327;8266;8304;5231.816886408996;5279.392869819982;5297.417284098746;5422.078246698071;5652.858678533028;1880.3606773696872;1923.519120219945;1931.4495368819819;1976.8319692826128;1989.8109679580416;26618.86690566554;26858.731412686015;26874.328141796417;26899.731002690114;26952.730472695217;6723.764668236686;6746.83132921671;6816.931830681729;6831.316868313228;6897.931020689829;17036.2333694984;17130.97214167129;17131.914340428466;17142.40001599963;17171.914140429468;7930.167332430127;7937.880931786028;7957.6021198939825;7960.761177164694;7962.601869906482;7020.894686579705;7344.081989751281;7393.334599886036;7393.741219057307;7411.444141689287;5034.395872495251;5196.45437229093;5203.453637368098;5254.264402983586;5295.285136506523;1421.204125689617;1481.222358261921;1496.9476068337556;1548.9767653485208;1552.2781906413572;895.2614093372994;911.8768966189613;918.2470374293149;930.3627015494408;934.9532523373804;7276.927230727731;7306.561606303639;7331.523450975669;7361.521501102411;7402.222766609536;976.4629453800381;987.0968064221157;989.7030890732896;992.6128809764198;1016.4765145949842;4340.457442819647;4392.736435813861;4481.35020422037;4517.570830771071;4525.389072475239;21488.355349339545;21518.677219841717;21722.89138554297;21926;21997;20930.37208883736;21589.13643454244;22043.803648471203;22049.22827701023;22069.11723531073;11434.656960291204;11711.238769479956;11719;11725.765484690259;11739.29564226132;8162.142974987541;8389.370797190113;8523.190296921783;8555.187257210555;8884.200421962054;18474.44576662702;18664.906675466536;19034.809651903302;19073.14170862287;19198.424047278604;19074.427767167006;19238.903805480884;19463.12415941258;19616.215351385785;19739.309124180585;12335.198212116184;12365.876341236832;12438.751224975269;12563.811542827048;12586.748265034465;14041.15753054779;14148.43406263779;14164.079334843202;14167.433302667778;14182.858171418566;11191.496382662637;11238.887611123784;11339.433028348714;11370;11441.771164576496;10845;10857.077148442155;10877.184211184034;10947.124230061736;10952.397618131079;9911.851322230028;10122.797544049225;10143.188544916242;10148.492575371349;10160.441175735405;6528.608283503005;6575.736970521317;6610.735570577123;7821.726239581815;7851.528908265523;7080.681369338495;7086.50394472382;7106.324899134574;7111.43108551325;7124.893126603209 16:10:21 INFO - PID 14309 | |4;prototype.html;497.31619023842205;509.95665368443656;537.4571682600574;539.3016044222732;545.1550097349108;6293.905591416133;6409.102725618417;6413.262474815392;6425.582337148088;6487.059376390421;6098.3901609838995;6136.23297087864;6207.689615519229;6210.285817131031;6307.243130824308;4979.950200498003;5007.924881126786;5042.445331013586;5053.4946505349535;5093.79624815007;4909.852704418855;5029.195328747401;5037.143685573443;5041;5083.576598552411;4850.733209673466;4939.185034469327;4973;4988.102141614508;5040.84877453677;4967.10592093423;4995.725235112068;4999.600031997432;5013.323201367805;5018.874528136807;4454;4465.642748580139;4483.78937686826;4487.259602165672;4508.571685689854;6058;6090.634561926255;6100.664463454508;6115.388461153893;6123.357047510036;5712.428757124296;5752.252207213078;5771.163181338682;5772.624779389326;5786.103154011093;6513.674316284214;6525.249596296392;6536.150300460958;6573.600635767911;6598.241202261754;4864.805407783684;4895.632827537949;4899.412070551521;4907.993861258434;4916.827911023097;6828.52200345976;6878.415334696557;6905.654717264167;6985.79042628722;7013.649317534103;5018.89962200754;5050.924236136461;5055.87360315995;5060.974695126574;5079.415867175322;5023;5028.899422011539;5062.240663900445;5103.693778373309;5112.744362781846;5688.800891968844;5690.260266165372;5741.913871291934;5762.2509073820975;5767;6562.278149403562;6613.735450582019;6676.232233293136;6711.919305015461;6768.763093291711;1645.7531370294553;1682.8401301876302;1721.8364255395718;1723.147042214112;1735.7483164941013;1193.3496244546743;1203.6449247472017;1224.5163160551533;1242.0125999830157;1248.775220460326;5667.659940403589;5766.769329226784;5804.564657650693;5819.6508209507565;5845.561582881216;6475.546711730133;6575.80272591823;6580.322017885409;6585.9167316263265;6629.502787290972;5729.627574207663;5745.569082318759;5765.625234359753;5773.538116950634;5806.65160090396;3507.7018453431233;3517.8416971236356;3517.982410087933;3551.2009797795804;3567.696745776638;6120.846978825476;6428.646424446607;6432.324605916405;6463.834615403798;6512.5766825156215;6745;6761.29006454325;6805.659717014229;6848;6864.279250678706;6283.3402492737605;6375.362463753588;6393.803098450775;6399.584027038134;6428.4214420702365;6543.672816359258;6598.3401659833635;6612.735490580323;6635.170603674541;6710.530262881552;6539.934600654123;6597.427003468555;6606.174228221473;6641.1034510342415;6675.966620166868;6384.499642584082;6395.264544577247;6432.549721519636;6485.967570162119;6486.578372405778;4948.901021979468;4975.0796102721115;4984.925226121539;4986.276989836526;5003.799848006184;6573.3426657333885;6628.5691430056895;6648.202215734143;6670;6672.933270667231;6384.553081284265;6413.454856337077;6432.9678351607945;6436.742530298736;6488.805335839932;6334.778282760081;6372.522060845363;6417.486601071996;6434.420902118832;6458.321876202931;6204.363145663166;6251.281102673251;6262.248530176408;6283.591566548343;6299.842503937438;6454;6463.515236357386;6485.902711459426;6531.934680653133;6544.770933017321;5847.415258474119;6192.783252586138;6290.811275661737;6320.747170113144;6397.3282805306635 16:10:21 INFO - PID 14309 | |5;yui.html;903.4669544968467;915.9679411220604;919.0625561926835;919.5815903763788;923.8060007398446;17944.64110717785;17957;17974.820251797493;17987.190576424076;20118;19035.333763318253;19127.234910603558;19162.616747665037;19178.520536986583;19242;713.4755954373558;735.8675438421083;938.2822141062093;966.4298064142172;995.7211980645395;998.9300748947577;1006.5621454667206;1007.8236308645958;1008.2337423558112;1010.7321559786662;1061.2730279758352;1061.9522121504551;1062.054771253585;1064.6965614799783;1064.8881867403927;1058.9629362972298;1067.236925598201;1067.5783065689047;1067.845162451448;1068.497806031168;769.1116760142045;769.8575763483774;778.353966208046;780.6252998560666;780.7228433905939;17202.397916072878;17279.568010799703;17363.47909562715;17636.735448968277;19347.4195774127;20970.475738106517;21285.14859405622;21833.563328733337;21862.562748744935;21914.12343506258;16122.516324510283;16532.83467165325;16580.253888575044;16689.582760430963;16708.49874503755;3787.431885217212;3820.5797362290127;3822;3823.1971286029966;3827.2728181645675;128991.35504322419;129415.35292323478;129921.35039324743;132314.33842830724;134131.32934335363;13798.379072941541;13855.376508057161;13900.721985560232;14156.575302740934;14210.786838197435;29432.705672942997;30408.54387184194;30452.238694032825;30878.0736577903;31536.526952095737;7959.403044771665;8067.959660201779;8094.392920530984;8094.959525202336;8100.756977290691;3348.748843836722;3543;3808.9047773805323;3940.6256405641993;4148.543660197376;5189.351331083614;5312.893742125136;5325.680459172462;5329.840104796862;5334.7065911374475;1082.0423924826428;1084.6637542361893;1119.0767616716241;1119.7536541960756;1122.1303489795473;603.3574243430733;619.4827319188438;631.2961048431058;648.4423395879539;653.8398092467376;11789.764204715857;12015.639530814089;12100.878991210151;13637.863621363858;14069.366878490493;678.1895634716565;698.6052880122816;703.6446594469862;717.9030830837875;739.9186089530148;5526.226328314039;5711.228984087178;5819.399665092134;5873.148393482921;6045.576809623372;113826;115172;115862.42068789771;116284;116561;100401.49799250957;101417;101442.49278753706;103401.48299258456;107480.46259768808;86859.56570217108;87043.56478217569;87703.56148219218;88087.11912880918;90539.54730226306;10750.51622676997;10831.67504974852;10833.349999000086;10848.294860834021;11261.380624065427;41204.175916480905;41208;41918;42305.78847105745;42968.57031429771;50642;53244.201336980754;54045;67187;71296.64351678209;18768.62462750765;19007.42977710671;19017.239310427427;19096.71354929705;19225.615487689887;17506.124693765516;18222.271109155485;19521;22689.978950946916;22901.31296061121;16186;17788.288468461633;18428.447146585622;18956.146973385956;19235.807641923402;18898.14958326906;18922.905385472983;19380.515487112934;19479;20155.093020813805;18304;19183.904080479508;19295.131719072393;19704.507387315432;19854.702179467607;13714.45142194341;14160.787588186391;14378.568642940729;14383.065100768417;14702.117872927658;13963.371648276056;14010.509632162826;14149.504767333094;14156.716865662835;14297 16:10:21 INFO - PID 14309 | __end_tp_report 16:10:21 INFO - PID 14309 | __start_cc_report 16:10:21 INFO - PID 14309 | _x_x_mozilla_cycle_collect,372 16:10:21 INFO - PID 14309 | __end_cc_report 16:10:21 INFO - PID 14309 | __startTimestamp1504393821635__endTimestamp 16:10:21 INFO - PID 14309 | 16:10:21 INFO - PID 14309 | ------- Summary: start ------- 16:10:21 INFO - PID 14309 | Number of tests: 6 16:10:21 INFO - PID 14309 | 16:10:21 INFO - PID 14309 | [#0] dojo.html Cycles:165 Average:6362.68 Median:6601.67 stddev:744.11 (11.3%) stddev-sans-first:706.94 16:10:21 INFO - PID 14309 | Values: 3315.0 3318.2 3321.7 3346.1 3397.8 6372.8 6502.6 6519.3 6554.5 6570.9 6369.4 6436.5 6450.0 6453.4 6465.3 5865.4 5897.9 5899.9 5932.5 5933.0 5877.7 5942.5 5964.0 6002.8 6024.7 5931.8 5962.8 6024.8 6036.9 6070.5 5871.4 5953.6 5959.0 5977.4 6077.5 5681.0 5757.1 5817.9 5865.0 5909.2 6385.0 6439.3 6489.1 6533.9 6586.5 6683.8 6755.2 6802.3 6840.9 6907.0 6700.4 6757.8 6825.9 6842.0 6901.4 6362.2 6392.1 6435.4 6439.5 6440.1 6949.3 7069.9 7135.0 7156.3 7160.5 6706.3 6754.8 6792.2 6824.0 6868.8 6801.4 6850.1 6869.1 6885.4 6928.2 6838.2 6854.8 6858.8 6920.4 6923.2 6864.1 6941.3 6969.3 6975.7 6977.7 5417.7 5449.3 5529.6 5535.4 5535.5 4669.6 4735.2 4742.2 4758.5 4822.6 5996.8 6018.2 6092.9 6102.0 6103.4 7003.0 7007.7 7064.0 7078.5 7085.1 6097.5 6104.3 6123.4 6190.5 6199.0 5833.1 5900.6 5934.1 5973.0 5973.1 6723.5 6779.6 6780.2 6782.6 6785.2 6822.8 6892.4 6994.6 7024.6 7032.2 6575.7 6648.5 6699.7 6750.4 6794.1 6839.0 6848.4 6852.1 6901.2 6919.6 6969.8 6979.9 7004.9 7077.3 7099.6 6679.6 6705.6 6755.8 6793.3 6797.7 6530.8 6614.9 6642.3 6729.3 6761.4 6677.1 6681.0 6685.0 6689.6 6689.8 6601.7 6665.1 6732.4 6745.9 6789.1 6409.7 6439.2 6444.0 6447.5 6457.3 16:10:21 INFO - PID 14309 | 16:10:21 INFO - PID 14309 | [#1] ext.html Cycles:180 Average:37987.09 Median:34045.66 stddev:55966.93 (164.4%) stddev-sans-first:56054.26 16:10:21 INFO - PID 14309 | Values: 795.0 841.9 872.2 878.7 1126.6 29639.6 30092.5 38472.0 39374.6 39807.6 40007.8 40320.4 40376.2 40402.6 40553.8 7200.2 7601.4 7807.3 7811.1 7814.5 7656.5 7756.0 7757.5 7769.3 7777.5 7759.5 7817.4 7823.2 7825.5 7832.8 7739.0 7750.9 7765.7 7776.7 7793.4 7668.2 7670.1 7732.6 7740.3 7753.1 36994.6 37080.0 37178.3 37273.0 37289.6 3508.1 3550.9 3555.1 3558.0 3558.7 30616.7 30671.0 30822.1 30883.7 31033.2 5047.4 5134.0 5135.2 5151.7 5178.2 199508.0 200138.0 200221.0 200229.0 200572.0 46124.8 46470.0 46601.0 46623.3 46984.5 47809.5 48060.0 48207.3 48317.0 48680.8 13966.2 15646.4 15748.8 15763.7 15777.9 11692.5 12766.3 12992.1 12997.0 13017.0 13539.7 13624.8 13663.0 13692.5 13831.7 5474.4 5542.6 5584.6 5616.2 5777.3 984.6 1007.9 1009.2 1015.4 1035.7 22631.8 28025.6 28055.7 28303.2 28681.7 1078.7 1087.5 1099.3 1099.9 1102.3 12159.2 12289.7 12342.6 12378.3 12394.0 236914.0 238998.0 239566.0 241408.0 241759.0 48627.8 49296.0 49331.8 49334.3 49397.5 1402.1 1428.2 1440.6 1444.3 1452.0 21988.3 21999.6 22044.9 22231.9 22464.2 47723.0 49282.3 49397.8 49480.5 49514.3 204538.0 205069.0 205751.0 206178.0 206387.0 30708.4 31098.8 33784.3 34027.8 34063.5 41633.8 41856.4 41898.4 42110.2 42145.8 30936.4 31080.8 31112.2 31544.4 31716.4 30236.7 30256.4 30414.5 30540.4 30799.5 34596.3 34685.7 34747.3 34767.5 34846.1 29555.4 29815.6 29877.0 29901.9 29987.0 22406.9 22489.3 24195.3 24323.6 24443.9 16:10:21 INFO - PID 14309 | 16:10:21 INFO - PID 14309 | [#2] jquery.html Cycles:180 Average:34793.25 Median:22391.00 stddev:83932.93 (374.9%) stddev-sans-first:84129.04 16:10:21 INFO - PID 14309 | Values: 564.9 566.2 569.5 574.9 589.7 21478.0 22352.8 22380.8 22389.3 23810.5 21157.7 21497.4 21500.0 21558.5 21782.9 6508.6 6684.8 6738.5 6759.5 6767.5 5960.9 6441.2 6463.5 6501.5 6564.0 6424.5 6432.5 6432.5 6433.7 6509.3 6416.6 6450.3 6508.1 6515.3 6519.7 6040.4 6046.5 6046.7 6052.2 6073.2 27520.4 28021.3 28203.2 28528.3 29059.3 2060.7 2090.8 2092.0 2093.4 2122.1 18795.5 19457.3 19557.4 19565.4 19718.5 2281.4 2307.7 2361.1 2362.2 2362.9 134712.0 140000.0 140366.0 141186.3 141226.3 13525.9 14318.8 14469.6 14530.6 14772.3 22392.7 22727.2 22889.4 23322.8 23344.5 9475.7 9544.1 9639.2 9650.8 9711.8 8739.6 8832.0 9413.9 9436.0 9489.4 8938.9 8979.1 8991.1 9111.5 9225.9 2652.1 2652.5 2659.5 2728.4 2821.3 957.4 983.4 995.6 1002.3 1003.7 19245.6 19578.7 19608.7 19723.5 19892.2 1035.7 1040.2 1044.7 1054.6 1054.7 10820.5 10956.9 10989.9 11059.6 11060.3 455429.0 460424.0 462147.0 547742.0 553667.0 113903.0 115294.0 119366.0 120880.0 120889.0 1197.5 1230.7 1274.1 1283.2 1284.2 20531.4 20664.4 20749.6 21179.2 21356.8 56059.0 57854.7 57939.7 58173.0 60337.0 67058.7 67307.0 67421.3 68238.3 68508.3 11993.8 12008.2 12640.9 12871.5 12888.2 22623.0 22883.1 22908.9 22922.8 22926.0 17575.4 17868.8 17878.2 18396.9 18628.6 16346.5 16587.4 16709.0 17013.4 17162.9 18621.6 18751.3 18902.1 18997.5 19009.3 14388.9 14431.3 14450.6 14520.3 14548.2 18162.5 18179.6 18263.4 19043.4 19081.2 16:10:21 INFO - PID 14309 | 16:10:21 INFO - PID 14309 | [#3] mootools.html Cycles:180 Average:9386.49 Median:26738.80 stddev:6766.14 (25.3%) stddev-sans-first:6757.91 16:10:21 INFO - PID 14309 | Values: 1310.8 1374.1 1380.7 1383.9 1389.4 14599.5 15108.6 15191.2 15264.6 15309.4 14787.3 15418.2 15846.3 15915.1 16013.8 2812.6 2855.6 2858.7 2861.0 2877.4 2694.8 2760.9 2818.0 2818.9 2819.7 2815.3 2862.2 2864.1 2865.6 2866.6 2714.0 2720.2 2749.4 2755.3 2759.5 2625.6 2630.0 2650.0 2652.4 2659.9 14802.5 14980.8 14984.1 15132.8 15143.1 7942.6 8204.5 8245.9 8266.0 8304.0 5231.8 5279.4 5297.4 5422.1 5652.9 1880.4 1923.5 1931.4 1976.8 1989.8 26618.9 26858.7 26874.3 26899.7 26952.7 6723.8 6746.8 6816.9 6831.3 6897.9 17036.2 17131.0 17131.9 17142.4 17171.9 7930.2 7937.9 7957.6 7960.8 7962.6 7020.9 7344.1 7393.3 7393.7 7411.4 5034.4 5196.5 5203.5 5254.3 5295.3 1421.2 1481.2 1496.9 1549.0 1552.3 895.3 911.9 918.2 930.4 935.0 7276.9 7306.6 7331.5 7361.5 7402.2 976.5 987.1 989.7 992.6 1016.5 4340.5 4392.7 4481.4 4517.6 4525.4 21488.4 21518.7 21722.9 21926.0 21997.0 20930.4 21589.1 22043.8 22049.2 22069.1 11434.7 11711.2 11719.0 11725.8 11739.3 8162.1 8389.4 8523.2 8555.2 8884.2 18474.4 18664.9 19034.8 19073.1 19198.4 19074.4 19238.9 19463.1 19616.2 19739.3 12335.2 12365.9 12438.8 12563.8 12586.7 14041.2 14148.4 14164.1 14167.4 14182.9 11191.5 11238.9 11339.4 11370.0 11441.8 10845.0 10857.1 10877.2 10947.1 10952.4 9911.9 10122.8 10143.2 10148.5 10160.4 6528.6 6575.7 6610.7 7821.7 7851.5 7080.7 7086.5 7106.3 7111.4 7124.9 16:10:21 INFO - PID 14309 | 16:10:21 INFO - PID 14309 | [#4] prototype.html Cycles:180 Average:5488.61 Median:6118.12 stddev:1529.47 (25.0%) stddev-sans-first:1487.17 16:10:21 INFO - PID 14309 | Values: 497.3 510.0 537.5 539.3 545.2 6293.9 6409.1 6413.3 6425.6 6487.1 6098.4 6136.2 6207.7 6210.3 6307.2 4980.0 5007.9 5042.4 5053.5 5093.8 4909.9 5029.2 5037.1 5041.0 5083.6 4850.7 4939.2 4973.0 4988.1 5040.8 4967.1 4995.7 4999.6 5013.3 5018.9 4454.0 4465.6 4483.8 4487.3 4508.6 6058.0 6090.6 6100.7 6115.4 6123.4 5712.4 5752.3 5771.2 5772.6 5786.1 6513.7 6525.2 6536.2 6573.6 6598.2 4864.8 4895.6 4899.4 4908.0 4916.8 6828.5 6878.4 6905.7 6985.8 7013.6 5018.9 5050.9 5055.9 5061.0 5079.4 5023.0 5028.9 5062.2 5103.7 5112.7 5688.8 5690.3 5741.9 5762.3 5767.0 6562.3 6613.7 6676.2 6711.9 6768.8 1645.8 1682.8 1721.8 1723.1 1735.7 1193.3 1203.6 1224.5 1242.0 1248.8 5667.7 5766.8 5804.6 5819.7 5845.6 6475.5 6575.8 6580.3 6585.9 6629.5 5729.6 5745.6 5765.6 5773.5 5806.7 3507.7 3517.8 3518.0 3551.2 3567.7 6120.8 6428.6 6432.3 6463.8 6512.6 6745.0 6761.3 6805.7 6848.0 6864.3 6283.3 6375.4 6393.8 6399.6 6428.4 6543.7 6598.3 6612.7 6635.2 6710.5 6539.9 6597.4 6606.2 6641.1 6676.0 6384.5 6395.3 6432.5 6486.0 6486.6 4948.9 4975.1 4984.9 4986.3 5003.8 6573.3 6628.6 6648.2 6670.0 6672.9 6384.6 6413.5 6433.0 6436.7 6488.8 6334.8 6372.5 6417.5 6434.4 6458.3 6204.4 6251.3 6262.2 6283.6 6299.8 6454.0 6463.5 6485.9 6531.9 6544.8 5847.4 6192.8 6290.8 6320.7 6397.3 16:10:21 INFO - PID 14309 | 16:10:21 INFO - PID 14309 | [#5] yui.html Cycles:180 Average:23870.62 Median:19230.71 stddev:33112.94 (172.2%) stddev-sans-first:33160.93 16:10:21 INFO - PID 14309 | Values: 903.5 916.0 919.1 919.6 923.8 17944.6 17957.0 17974.8 17987.2 20118.0 19035.3 19127.2 19162.6 19178.5 19242.0 713.5 735.9 938.3 966.4 995.7 998.9 1006.6 1007.8 1008.2 1010.7 1061.3 1062.0 1062.1 1064.7 1064.9 1059.0 1067.2 1067.6 1067.8 1068.5 769.1 769.9 778.4 780.6 780.7 17202.4 17279.6 17363.5 17636.7 19347.4 20970.5 21285.1 21833.6 21862.6 21914.1 16122.5 16532.8 16580.3 16689.6 16708.5 3787.4 3820.6 3822.0 3823.2 3827.3 128991.4 129415.4 129921.4 132314.3 134131.3 13798.4 13855.4 13900.7 14156.6 14210.8 29432.7 30408.5 30452.2 30878.1 31536.5 7959.4 8068.0 8094.4 8095.0 8100.8 3348.7 3543.0 3808.9 3940.6 4148.5 5189.4 5312.9 5325.7 5329.8 5334.7 1082.0 1084.7 1119.1 1119.8 1122.1 603.4 619.5 631.3 648.4 653.8 11789.8 12015.6 12100.9 13637.9 14069.4 678.2 698.6 703.6 717.9 739.9 5526.2 5711.2 5819.4 5873.1 6045.6 113826.0 115172.0 115862.4 116284.0 116561.0 100401.5 101417.0 101442.5 103401.5 107480.5 86859.6 87043.6 87703.6 88087.1 90539.5 10750.5 10831.7 10833.3 10848.3 11261.4 41204.2 41208.0 41918.0 42305.8 42968.6 50642.0 53244.2 54045.0 67187.0 71296.6 18768.6 19007.4 19017.2 19096.7 19225.6 17506.1 18222.3 19521.0 22690.0 22901.3 16186.0 17788.3 18428.4 18956.1 19235.8 18898.1 18922.9 19380.5 19479.0 20155.1 18304.0 19183.9 19295.1 19704.5 19854.7 13714.5 14160.8 14378.6 14383.1 14702.1 13963.4 14010.5 14149.5 14156.7 14297.0 16:10:21 INFO - PID 14309 | -------- Summary: end -------- 16:10:21 INFO - PID 14309 | 16:10:22 INFO - TEST-INFO | 14309: exit 0 16:10:22 INFO - TEST-OK | dromaeo_css | took 1111522ms 16:10:22 INFO - TEST-START | kraken 16:10:22 INFO - Initialising browser for kraken test... 16:10:22 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36701/getInfo.html -profile /tmp/tmpGTNJwL/profile 16:10:22 INFO - TEST-INFO | started process 14683 (/builds/slave/test/build/application/firefox/firefox http://localhost:36701/getInfo.html) 16:10:27 INFO - TEST-INFO | 14683: exit 0 16:10:27 INFO - Browser initialized. 16:10:27 INFO - Running cycle 1/1 for kraken test... 16:10:27 INFO - Using env: {'DISPLAY': ':0', 16:10:27 INFO - 'HOME': '/home/cltbld', 16:10:27 INFO - 'JSGC_DISABLE_POISONING': '1', 16:10:27 INFO - 'LANG': 'en_US.UTF-8', 16:10:27 INFO - 'LANGUAGE': 'en_US:en', 16:10:27 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:10:28 INFO - 'LOGNAME': 'cltbld', 16:10:28 INFO - 'MAIL': '/var/mail/cltbld', 16:10:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:10:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:10:28 INFO - 'MOZ_CRASHREPORTER': '1', 16:10:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:28 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:10:28 INFO - 'MOZ_NO_REMOTE': '1', 16:10:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:10:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:28 INFO - 'NO_EM_RESTART': '1', 16:10:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:28 INFO - 'PWD': '/builds/slave/test', 16:10:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:10:28 INFO - 'RUST_BACKTRACE': 'full', 16:10:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:10:28 INFO - 'SHELL': '/bin/bash', 16:10:28 INFO - 'SHLVL': '1', 16:10:28 INFO - 'STYLO_FORCE_ENABLED': '1', 16:10:28 INFO - 'TERM': 'linux', 16:10:28 INFO - 'TMOUT': '86400', 16:10:28 INFO - 'USER': 'cltbld', 16:10:28 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354', 16:10:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:28 INFO - '_': '/tools/buildbot/bin/python'} 16:10:28 INFO - TEST-INFO | started process 14878 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGTNJwL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:10:28 INFO - PID 14878 | 16:10:28 INFO - PID 14878 | (/builds/slave/test/build/application/firefox/firefox:14930): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:10:28 INFO - PID 14878 | 16:10:39 INFO - PID 14878 | RSS: Main: 172462080 16:10:39 INFO - PID 14878 | 16:10:41 INFO - PID 14878 | Cycle 1(1): loaded http://localhost:36701/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36701/tests/kraken/driver.html?testName=audio-beat-detection) 16:10:42 INFO - PID 14878 | RSS: Main: 178225152 16:10:42 INFO - PID 14878 | 16:10:45 INFO - PID 14878 | Cycle 1(1): loaded http://localhost:36701/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36701/tests/kraken/driver.html?testName=audio-dft) 16:10:46 INFO - PID 14878 | RSS: Main: 178552832 16:10:46 INFO - PID 14878 | 16:10:49 INFO - PID 14878 | Cycle 1(1): loaded http://localhost:36701/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36701/tests/kraken/driver.html?testName=audio-fft) 16:10:49 INFO - PID 14878 | RSS: Main: 172081152 16:10:49 INFO - PID 14878 | 16:10:51 INFO - PID 14878 | Cycle 1(1): loaded http://localhost:36701/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36701/tests/kraken/driver.html?testName=audio-oscillator) 16:10:52 INFO - PID 14878 | RSS: Main: 172335104 16:10:52 INFO - PID 14878 | 16:10:54 INFO - PID 14878 | Cycle 1(1): loaded http://localhost:36701/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36701/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:10:55 INFO - PID 14878 | RSS: Main: 172843008 16:10:55 INFO - PID 14878 | 16:10:58 INFO - PID 14878 | Cycle 1(1): loaded http://localhost:36701/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36701/tests/kraken/driver.html?testName=imaging-darkroom) 16:10:59 INFO - PID 14878 | RSS: Main: 175349760 16:10:59 INFO - PID 14878 | 16:11:02 INFO - PID 14878 | Cycle 1(1): loaded http://localhost:36701/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36701/tests/kraken/driver.html?testName=imaging-desaturate) 16:11:03 INFO - PID 14878 | RSS: Main: 175190016 16:11:03 INFO - PID 14878 | 16:11:06 INFO - PID 14878 | Cycle 1(1): loaded http://localhost:36701/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36701/tests/kraken/driver.html?testName=json-parse-financial) 16:11:06 INFO - PID 14878 | RSS: Main: 176308224 16:11:06 INFO - PID 14878 | 16:11:08 INFO - PID 14878 | Cycle 1(1): loaded http://localhost:36701/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36701/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:11:08 INFO - PID 14878 | RSS: Main: 175607808 16:11:08 INFO - PID 14878 | 16:11:10 INFO - PID 14878 | Cycle 1(1): loaded http://localhost:36701/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36701/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:11:11 INFO - PID 14878 | RSS: Main: 175730688 16:11:11 INFO - PID 14878 | 16:11:13 INFO - PID 14878 | Cycle 1(1): loaded http://localhost:36701/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36701/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:11:13 INFO - PID 14878 | RSS: Main: 172695552 16:11:13 INFO - PID 14878 | 16:11:16 INFO - PID 14878 | Cycle 1(1): loaded http://localhost:36701/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36701/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:11:17 INFO - PID 14878 | RSS: Main: 172986368 16:11:17 INFO - PID 14878 | 16:11:20 INFO - PID 14878 | Cycle 1(1): loaded http://localhost:36701/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36701/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:11:20 INFO - PID 14878 | RSS: Main: 173027328 16:11:20 INFO - PID 14878 | 16:11:22 INFO - PID 14878 | Cycle 1(1): loaded http://localhost:36701/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36701/tests/kraken/driver.html?testName=ai-astar) 16:11:23 INFO - PID 14878 | RSS: Main: 174891008 16:11:23 INFO - PID 14878 | 16:11:23 INFO - PID 14878 | __start_tp_report 16:11:23 INFO - PID 14878 | _x_x_mozilla_page_load 16:11:23 INFO - PID 14878 | _x_x_mozilla_page_load_details 16:11:23 INFO - PID 14878 | |i|pagename|runs| 16:11:23 INFO - PID 14878 | |0;ai-astar;101;108;100;107;108;119;108;100;110;110 16:11:23 INFO - PID 14878 | |1;audio-beat-detection;149;116;147;179;119;146;144;121;169;118 16:11:23 INFO - PID 14878 | |2;audio-dft;164;164;164;163;163;164;163;176;162;165 16:11:23 INFO - PID 14878 | |3;audio-fft;75;73;74;171;75;75;74;73;151;75 16:11:23 INFO - PID 14878 | |4;audio-oscillator;80;79;126;80;79;80;123;81;80;138 16:11:23 INFO - PID 14878 | |5;imaging-gaussian-blur;114;114;115;113;114;114;115;115;115;113 16:11:23 INFO - PID 14878 | |6;imaging-darkroom;149;151;152;149;149;150;150;149;150;151 16:11:23 INFO - PID 14878 | |7;imaging-desaturate;95;95;94;96;94;94;94;95;97;95 16:11:23 INFO - PID 14878 | |8;json-parse-financial;52;51;52;52;51;52;53;52;52;53 16:11:23 INFO - PID 14878 | |9;json-stringify-tinderbox;38;38;38;38;39;37;38;38;38;38 16:11:23 INFO - PID 14878 | |10;stanford-crypto-aes;76;76;74;75;76;74;75;78;77;77 16:11:23 INFO - PID 14878 | |11;stanford-crypto-ccm;126;132;125;135;123;126;124;129;118;168 16:11:23 INFO - PID 14878 | |12;stanford-crypto-pbkdf2;171;172;180;177;170;171;169;171;172;166 16:11:23 INFO - PID 14878 | |13;stanford-crypto-sha256-iterative;78;70;64;72;65;67;64;66;65;66 16:11:23 INFO - PID 14878 | __end_tp_report 16:11:23 INFO - PID 14878 | __start_cc_report 16:11:23 INFO - PID 14878 | _x_x_mozilla_cycle_collect,903 16:11:23 INFO - PID 14878 | __end_cc_report 16:11:23 INFO - PID 14878 | __startTimestamp1504393883018__endTimestamp 16:11:23 INFO - PID 14878 | 16:11:23 INFO - PID 14878 | ------- Summary: start ------- 16:11:23 INFO - PID 14878 | Number of tests: 14 16:11:23 INFO - PID 14878 | 16:11:23 INFO - PID 14878 | [#0] ai-astar Cycles:10 Average:107.10 Median:108.00 stddev:5.76 (5.3%) stddev-sans-first:5.67 16:11:23 INFO - PID 14878 | Values: 101.0 108.0 100.0 107.0 108.0 119.0 108.0 100.0 110.0 110.0 16:11:23 INFO - PID 14878 | 16:11:23 INFO - PID 14878 | [#1] audio-beat-detection Cycles:10 Average:140.80 Median:146.50 stddev:22.11 (15.1%) stddev-sans-first:23.25 16:11:23 INFO - PID 14878 | Values: 149.0 116.0 147.0 179.0 119.0 146.0 144.0 121.0 169.0 118.0 16:11:23 INFO - PID 14878 | 16:11:23 INFO - PID 14878 | [#2] audio-dft Cycles:10 Average:164.80 Median:164.00 stddev:4.02 (2.5%) stddev-sans-first:4.26 16:11:23 INFO - PID 14878 | Values: 164.0 164.0 164.0 163.0 163.0 164.0 163.0 176.0 162.0 165.0 16:11:23 INFO - PID 14878 | 16:11:23 INFO - PID 14878 | [#3] audio-fft Cycles:10 Average:91.60 Median:74.50 stddev:36.89 (49.5%) stddev-sans-first:38.63 16:11:23 INFO - PID 14878 | Values: 75.0 73.0 74.0 171.0 75.0 75.0 74.0 73.0 151.0 75.0 16:11:23 INFO - PID 14878 | 16:11:23 INFO - PID 14878 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:80.00 stddev:24.04 (30.0%) stddev-sans-first:24.91 16:11:23 INFO - PID 14878 | Values: 80.0 79.0 126.0 80.0 79.0 80.0 123.0 81.0 80.0 138.0 16:11:23 INFO - PID 14878 | 16:11:23 INFO - PID 14878 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.50 stddev:0.79 (0.7%) stddev-sans-first:0.83 16:11:23 INFO - PID 14878 | Values: 114.0 114.0 115.0 113.0 114.0 114.0 115.0 115.0 115.0 113.0 16:11:23 INFO - PID 14878 | 16:11:23 INFO - PID 14878 | [#6] imaging-darkroom Cycles:10 Average:150.00 Median:150.00 stddev:1.05 (0.7%) stddev-sans-first:1.05 16:11:23 INFO - PID 14878 | Values: 149.0 151.0 152.0 149.0 149.0 150.0 150.0 149.0 150.0 151.0 16:11:23 INFO - PID 14878 | 16:11:23 INFO - PID 14878 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.99 (1.0%) stddev-sans-first:1.05 16:11:23 INFO - PID 14878 | Values: 95.0 95.0 94.0 96.0 94.0 94.0 94.0 95.0 97.0 95.0 16:11:23 INFO - PID 14878 | 16:11:23 INFO - PID 14878 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:52.00 stddev:0.67 (1.3%) stddev-sans-first:0.71 16:11:23 INFO - PID 14878 | Values: 52.0 51.0 52.0 52.0 51.0 52.0 53.0 52.0 52.0 53.0 16:11:23 INFO - PID 14878 | 16:11:23 INFO - PID 14878 | [#9] json-stringify-tinderbox Cycles:10 Average:38.00 Median:38.00 stddev:0.47 (1.2%) stddev-sans-first:0.50 16:11:23 INFO - PID 14878 | Values: 38.0 38.0 38.0 38.0 39.0 37.0 38.0 38.0 38.0 38.0 16:11:23 INFO - PID 14878 | 16:11:23 INFO - PID 14878 | [#10] stanford-crypto-aes Cycles:10 Average:75.80 Median:76.00 stddev:1.32 (1.7%) stddev-sans-first:1.39 16:11:23 INFO - PID 14878 | Values: 76.0 76.0 74.0 75.0 76.0 74.0 75.0 78.0 77.0 77.0 16:11:23 INFO - PID 14878 | 16:11:23 INFO - PID 14878 | [#11] stanford-crypto-ccm Cycles:10 Average:130.60 Median:127.50 stddev:13.97 (11.0%) stddev-sans-first:14.72 16:11:23 INFO - PID 14878 | Values: 126.0 132.0 125.0 135.0 123.0 126.0 124.0 129.0 118.0 168.0 16:11:23 INFO - PID 14878 | 16:11:23 INFO - PID 14878 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:171.50 stddev:3.96 (2.3%) stddev-sans-first:4.18 16:11:23 INFO - PID 14878 | Values: 171.0 172.0 180.0 177.0 170.0 171.0 169.0 171.0 172.0 166.0 16:11:23 INFO - PID 14878 | 16:11:23 INFO - PID 14878 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:66.50 stddev:4.45 (6.7%) stddev-sans-first:2.74 16:11:23 INFO - PID 14878 | Values: 78.0 70.0 64.0 72.0 65.0 67.0 64.0 66.0 65.0 66.0 16:11:23 INFO - PID 14878 | -------- Summary: end -------- 16:11:23 INFO - PID 14878 | 16:11:23 INFO - PID 14878 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:11:23 INFO - TEST-INFO | 14878: exit 0 16:11:23 INFO - TEST-OK | kraken | took 61381ms 16:11:23 INFO - SUITE-END | took 1172s 16:11:24 INFO - Completed test suite (00:19:33) 16:11:24 INFO - javascript benchmark 16:11:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8550.039722438658, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3314.9502757458636, 3318.2202182487113, 3321.684439978201, 3346.0631023313467, 3397.813120278384, 6372.776952806653, 6502.609843409393, 6519.250286217087, 6554.508411869117, 6570.934290657098, 6369.363063693629, 6436.452901503378, 6450.0, 6453.386928241821, 6465.256495503018, 5865.413458654122, 5897.882042359161, 5899.911501327483, 5932.525397968152, 5932.99139146344, 5877.70611469427, 5942.494887934531, 5963.970180149093, 6002.759889604411, 6024.698765061751, 5931.822045338647, 5962.79130230442, 6024.789132380368, 6036.909446358308, 6070.48400885923, 5871.442212989759, 5953.642781433085, 5958.97020514899, 5977.3723759005115, 6077.483413909823, 5680.971595142039, 5757.136429535603, 5817.88364232713, 5865.002949498596, 5909.2022576951995, 6384.968075159641, 6439.323870993572, 6489.123968264271, 6533.889238829411, 6586.538942274043, 6683.799486015428, 6755.1556055493065, 6802.285759995229, 6840.931590684079, 6906.965465172642, 6700.430463410616, 6757.797266082025, 6825.897611535831, 6841.973703944398, 6901.378875901143, 6362.172917520693, 6392.137061496732, 6435.388638079375, 6439.452646525004, 6440.066190402412, 6949.30506949301, 7069.8939515907305, 7135.0, 7156.320149585781, 7160.462965277625, 6706.295838936939, 6754.797356079325, 6792.184937807495, 6823.965880170567, 6868.793936181923, 6801.387875091265, 6850.143732033496, 6869.141357330333, 6885.380315771605, 6928.237893831674, 6838.247792742794, 6854.794356169223, 6858.794236172923, 6920.377166055079, 6923.169219693568, 6864.141982252218, 6941.271166527543, 6969.303069693092, 6975.720971161097, 6977.720891164398, 5417.702026388586, 5449.3460784706085, 5529.585281103853, 5535.391106978229, 5535.529479994165, 4669.603083737852, 4735.195016847144, 4742.217534106902, 4758.547937945889, 4822.590079843182, 5996.820095397145, 6018.187544681499, 6092.878142437126, 6102.023676211785, 6103.389661033861, 7003.0, 7007.684654190573, 7064.046353742179, 7078.469114816409, 7085.114360704912, 6097.481714054266, 6104.272194413236, 6123.38766123393, 6190.473809726133, 6198.969005154945, 5833.125031245348, 5900.64596124234, 5934.109883517507, 5973.0, 5973.074173503114, 6723.5293529452465, 6779.559328643622, 6780.220274668476, 6782.626955517494, 6785.185777706608, 6822.761203357859, 6892.379685828099, 6994.5803251805055, 7024.578525288499, 7032.1912980007955, 6575.704093315908, 6648.468122550088, 6699.698513566998, 6750.35871592198, 6794.116764820542, 6839.0, 6848.383645471931, 6852.074969879002, 6901.1718593769065, 6919.619420931897, 6969.75605853813, 6979.930200697928, 7004.929950700428, 7077.292270772881, 7099.574025558483, 6679.632620205741, 6705.564138330993, 6755.7973260802255, 6793.320667933365, 6797.6941037654415, 6530.8367290818105, 6614.900776488453, 6642.302558231316, 6729.259781423942, 6761.391474767294, 6677.09859169006, 6681.0, 6685.0306705526455, 6689.632070236184, 6689.76585819494, 6601.669916504252, 6665.133532640726, 6732.394084532416, 6745.93254067453, 6789.1174147360525, 6409.679516024087, 6439.227292724903, 6444.033394990789, 6447.516436267205, 6457.257415397289], "value": 6307.909189676113, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [794.9920500794994, 841.8526757817382, 872.2367928062945, 878.7100256915219, 1126.611319094912, 29639.55540666889, 30092.548611770842, 38471.99999999996, 39374.606253937454, 39807.60192398075, 40007.79996100023, 40320.395194072036, 40376.192476150536, 40402.595974040254, 40553.79723101381, 7200.171980222289, 7601.353884919775, 7807.297343239107, 7811.06267247931, 7814.531128132302, 7656.502327348732, 7756.0, 7757.534547927143, 7769.339606133485, 7777.494462859924, 7759.495632783879, 7817.374610031212, 7823.178566250547, 7825.530468171928, 7832.843343133134, 7738.961305193466, 7750.922490775105, 7765.728199513046, 7776.688932442696, 7793.376529877596, 7668.194839541824, 7670.117936437269, 7732.613369331567, 7740.303372696484, 7753.147153813076, 36994.63005369939, 37080.0, 37178.25643487115, 37273.0, 37289.62710372916, 3508.0703613542432, 3550.928981420357, 3555.1289933966345, 3557.9822100889587, 3558.697510711567, 30616.69383306183, 30671.0, 30822.075337739905, 30883.69116308853, 31033.224169395944, 5047.394312682465, 5133.998870220308, 5135.229715542699, 5151.6908985461, 5178.197379406198, 199507.99999999854, 200138.0, 200221.0, 200229.0, 200572.0, 46124.76937615291, 46470.0, 46601.0, 46623.30065049027, 46984.530154698696, 47809.52190478051, 48060.0, 48207.27689084667, 48317.0, 48680.756596216794, 13966.162030278216, 15646.374145034299, 15748.76376854348, 15763.68472630541, 15777.921110394374, 11692.532298708127, 12766.29785361814, 12992.090553661154, 12997.0, 13017.0, 13539.729205415837, 13624.795628065587, 13663.0, 13692.520761773489, 13831.654208644864, 5474.350526168644, 5542.584306177053, 5584.636998595079, 5616.185653080281, 5777.306723193244, 984.6258421799813, 1007.9143272821746, 1009.2027298434155, 1015.4059874973224, 1035.746242170673, 22631.773682262967, 28025.57961630577, 28055.71944280572, 28303.15090547287, 28681.713182868323, 1078.6656136597524, 1087.499750114956, 1099.2689861242184, 1099.8680158381046, 1102.2559772153763, 12159.209651372632, 12289.69275768113, 12342.568010119783, 12378.257304561756, 12394.0, 236914.0, 238998.0, 239566.0, 241408.0, 241759.0, 48627.75686121618, 49296.0, 49331.75334123307, 49334.25998610024, 49397.50602493929, 1402.144691738039, 1428.178797191619, 1440.6038339456734, 1444.3428240150706, 1452.0416525093387, 21988.34034978953, 21999.560008799413, 22044.88977555102, 22231.888840555694, 22464.213752518583, 47723.0, 49282.26076608925, 49397.753011234716, 49480.50519494759, 49514.25728614146, 204538.0, 205069.0, 205751.0, 206178.0, 206387.0, 30708.385832283675, 31098.844505777328, 33784.3243135131, 34027.829860850536, 34063.48904766381, 41633.79183104065, 41856.3721544171, 41898.37152442655, 42110.157796844505, 42145.78927105345, 30936.381272374878, 31080.844595776878, 31112.22219444532, 31544.36911261808, 31716.36567268688, 30236.69763302427, 30256.394872102876, 30414.54378184373, 30540.389192216477, 30799.538006929466, 34596.30807383889, 34685.65314346824, 34747.30505389928, 34767.47848782321, 34846.12884677904, 29555.408891821615, 29815.552766708082, 29877.0, 29901.850490747405, 29987.0, 22406.88796556007, 22489.32532024042, 24195.274141775775, 24323.63514547248, 24443.877780610983], "value": 17058.11401248386, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.9096144616859, 566.1988286574498, 569.4647031790114, 574.9281339832521, 589.716935870782, 21477.99999999998, 22352.77647223529, 22380.776192238074, 22389.328320150402, 23810.5237895242, 21157.68263476049, 21497.355079347646, 21500.0, 21558.461038474044, 21782.89108554455, 6508.576942498746, 6684.766033188839, 6738.494612904052, 6759.459243260528, 6767.49243806714, 5960.910586341209, 6441.227052753653, 6463.48292136628, 6501.4798816094835, 6564.04821300911, 6424.486041116723, 6432.453241474457, 6432.5497215194955, 6433.7104830282515, 6509.316521765218, 6416.582922110095, 6450.322716114834, 6508.121403610499, 6515.283318834924, 6519.706613202417, 6040.365761595057, 6046.546509011798, 6046.667433291167, 6052.152698622196, 6073.210482637273, 27520.44959100827, 28021.299467513272, 28203.15390538287, 28528.28679283014, 29059.273518162005, 2060.721802556651, 2090.843186760999, 2092.0376626751713, 2093.371988403488, 2122.0662908320446, 18795.53011174718, 19457.318993835146, 19557.413277601696, 19565.413037608894, 19718.507037324038, 2281.361218858722, 2307.734610519778, 2361.1027809432476, 2362.173239366225, 2362.9291121266388, 134712.0, 140000.0, 140366.0, 141186.29406853003, 141226.29386853, 13525.864741352461, 14318.785218221736, 14469.565913022627, 14530.564083077525, 14772.261386930826, 22392.664110038364, 22727.204547840745, 22889.427764306027, 23322.76677233206, 23344.53310933806, 9475.715728528156, 9544.141027307436, 9639.228861691188, 9650.806983860424, 9711.757206069904, 8739.56302184901, 8832.0, 9413.90586094144, 9436.0, 9489.430634161972, 8938.932785582545, 8979.10208979097, 8991.14584114508, 9111.498867562215, 9225.861612075823, 2652.1465717736496, 2652.53580623394, 2659.5478768609382, 2728.358835673615, 2821.2523681224493, 957.4351132831523, 983.3657291047234, 995.6117114325419, 1002.2733518199393, 1003.7440452684518, 19245.615087698166, 19578.70631940522, 19608.724309425437, 19723.50691232702, 19892.204311827365, 1035.7048241251207, 1040.2406243442329, 1044.6970378590124, 1054.6097943760858, 1054.6888667843004, 10820.458977051116, 10956.890431095588, 10989.890101099047, 11059.612913547988, 11060.281081729661, 455429.0, 460424.0, 462147.0, 547742.0, 553667.0, 113903.0, 115294.0, 119366.0, 120880.0, 120889.0, 1197.4910662968205, 1230.7292395672937, 1274.1335891593806, 1283.223649691923, 1284.216627857025, 20531.38405847827, 20664.380068597966, 20749.58500830005, 21179.152833886474, 21356.7864321361, 56059.0, 57854.7107264461, 57939.71030144822, 58173.0, 60337.0, 67058.66470667615, 67307.0, 67421.32578674151, 68238.3176168232, 68508.3149168502, 11993.76012479728, 12008.159428839897, 12640.87359126397, 12871.485140594272, 12888.226706397647, 22623.0, 22883.084676613416, 22908.885455572614, 22922.770772292064, 22926.0, 17575.384861529787, 17868.821311787236, 17878.19548120364, 18396.908015459838, 18628.627427451647, 16346.509604711877, 16587.419440319532, 16709.0, 17013.404530841362, 17162.914185428992, 18621.627567448846, 18751.34370297033, 18902.14940327661, 18997.525061873563, 19009.33467328637, 14388.85611143917, 14431.278436078364, 14450.638734031734, 14520.273986300854, 14548.199849008406, 18162.45512634623, 18179.636407272046, 18263.360782372554, 19043.42869713911, 19081.236750530377], "value": 11862.273383518976, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1310.7902735562309, 1374.0862326552838, 1380.654836290928, 1383.9238841863705, 1389.4164450930612, 14599.489017884376, 15108.622284442894, 15191.164485953268, 15264.618384540392, 15309.38762449502, 14787.260636968136, 15418.152001639904, 15846.28691708873, 15915.124668143277, 16013.83986160138, 2812.606235127084, 2855.6144920435786, 2858.6712528059334, 2860.957085643717, 2877.352595665979, 2694.8113632045765, 2760.875760590768, 2818.027780415753, 2818.9154325370273, 2819.746222839954, 2815.282103063716, 2862.155664079102, 2864.097809190109, 2865.627468429101, 2866.5700144978214, 2714.0229517373828, 2720.2383332666886, 2749.4363655450584, 2755.2836262571677, 2759.4619049285297, 2625.619285203654, 2629.9868500657567, 2650.045983445958, 2652.350174207312, 2659.8670066496793, 14802.481913132988, 14980.775288370683, 14984.100953942798, 15132.773008404884, 15143.091414515164, 7942.602869856484, 8204.507729536188, 8245.876311855327, 8266.0, 8304.0, 5231.816886408996, 5279.392869819982, 5297.417284098746, 5422.078246698071, 5652.858678533028, 1880.3606773696872, 1923.519120219945, 1931.4495368819819, 1976.8319692826128, 1989.8109679580416, 26618.86690566554, 26858.731412686015, 26874.328141796417, 26899.731002690114, 26952.730472695217, 6723.764668236686, 6746.83132921671, 6816.931830681729, 6831.316868313228, 6897.931020689829, 17036.2333694984, 17130.97214167129, 17131.914340428466, 17142.40001599963, 17171.914140429468, 7930.167332430127, 7937.880931786028, 7957.6021198939825, 7960.761177164694, 7962.601869906482, 7020.894686579705, 7344.081989751281, 7393.334599886036, 7393.741219057307, 7411.444141689287, 5034.395872495251, 5196.45437229093, 5203.453637368098, 5254.264402983586, 5295.285136506523, 1421.204125689617, 1481.222358261921, 1496.9476068337556, 1548.9767653485208, 1552.2781906413572, 895.2614093372994, 911.8768966189613, 918.2470374293149, 930.3627015494408, 934.9532523373804, 7276.927230727731, 7306.561606303639, 7331.523450975669, 7361.521501102411, 7402.222766609536, 976.4629453800381, 987.0968064221157, 989.7030890732896, 992.6128809764198, 1016.4765145949842, 4340.457442819647, 4392.736435813861, 4481.35020422037, 4517.570830771071, 4525.389072475239, 21488.355349339545, 21518.677219841717, 21722.89138554297, 21926.0, 21997.0, 20930.37208883736, 21589.13643454244, 22043.803648471203, 22049.22827701023, 22069.11723531073, 11434.656960291204, 11711.238769479956, 11719.0, 11725.765484690259, 11739.29564226132, 8162.142974987541, 8389.370797190113, 8523.190296921783, 8555.187257210555, 8884.200421962054, 18474.44576662702, 18664.906675466536, 19034.809651903302, 19073.14170862287, 19198.424047278604, 19074.427767167006, 19238.903805480884, 19463.12415941258, 19616.215351385785, 19739.309124180585, 12335.198212116184, 12365.876341236832, 12438.751224975269, 12563.811542827048, 12586.748265034465, 14041.15753054779, 14148.43406263779, 14164.079334843202, 14167.433302667778, 14182.858171418566, 11191.496382662637, 11238.887611123784, 11339.433028348714, 11370.0, 11441.771164576496, 10845.0, 10857.077148442155, 10877.184211184034, 10947.124230061736, 10952.397618131079, 9911.851322230028, 10122.797544049225, 10143.188544916242, 10148.492575371349, 10160.441175735405, 6528.608283503005, 6575.736970521317, 6610.735570577123, 7821.726239581815, 7851.528908265523, 7080.681369338495, 7086.50394472382, 7106.324899134574, 7111.43108551325, 7124.893126603209], "value": 6698.525205857924, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.31619023842205, 509.95665368443656, 537.4571682600574, 539.3016044222732, 545.1550097349108, 6293.905591416133, 6409.102725618417, 6413.262474815392, 6425.582337148088, 6487.059376390421, 6098.3901609838995, 6136.23297087864, 6207.689615519229, 6210.285817131031, 6307.243130824308, 4979.950200498003, 5007.924881126786, 5042.445331013586, 5053.4946505349535, 5093.79624815007, 4909.852704418855, 5029.195328747401, 5037.143685573443, 5041.0, 5083.576598552411, 4850.733209673466, 4939.185034469327, 4973.0, 4988.102141614508, 5040.84877453677, 4967.10592093423, 4995.725235112068, 4999.600031997432, 5013.323201367805, 5018.874528136807, 4454.0, 4465.642748580139, 4483.78937686826, 4487.259602165672, 4508.571685689854, 6058.0, 6090.634561926255, 6100.664463454508, 6115.388461153893, 6123.357047510036, 5712.428757124296, 5752.252207213078, 5771.163181338682, 5772.624779389326, 5786.103154011093, 6513.674316284214, 6525.249596296392, 6536.150300460958, 6573.600635767911, 6598.241202261754, 4864.805407783684, 4895.632827537949, 4899.412070551521, 4907.993861258434, 4916.827911023097, 6828.52200345976, 6878.415334696557, 6905.654717264167, 6985.79042628722, 7013.649317534103, 5018.89962200754, 5050.924236136461, 5055.87360315995, 5060.974695126574, 5079.415867175322, 5023.0, 5028.899422011539, 5062.240663900445, 5103.693778373309, 5112.744362781846, 5688.800891968844, 5690.260266165372, 5741.913871291934, 5762.2509073820975, 5767.0, 6562.278149403562, 6613.735450582019, 6676.232233293136, 6711.919305015461, 6768.763093291711, 1645.7531370294553, 1682.8401301876302, 1721.8364255395718, 1723.147042214112, 1735.7483164941013, 1193.3496244546743, 1203.6449247472017, 1224.5163160551533, 1242.0125999830157, 1248.775220460326, 5667.659940403589, 5766.769329226784, 5804.564657650693, 5819.6508209507565, 5845.561582881216, 6475.546711730133, 6575.80272591823, 6580.322017885409, 6585.9167316263265, 6629.502787290972, 5729.627574207663, 5745.569082318759, 5765.625234359753, 5773.538116950634, 5806.65160090396, 3507.7018453431233, 3517.8416971236356, 3517.982410087933, 3551.2009797795804, 3567.696745776638, 6120.846978825476, 6428.646424446607, 6432.324605916405, 6463.834615403798, 6512.5766825156215, 6745.0, 6761.29006454325, 6805.659717014229, 6848.0, 6864.279250678706, 6283.3402492737605, 6375.362463753588, 6393.803098450775, 6399.584027038134, 6428.4214420702365, 6543.672816359258, 6598.3401659833635, 6612.735490580323, 6635.170603674541, 6710.530262881552, 6539.934600654123, 6597.427003468555, 6606.174228221473, 6641.1034510342415, 6675.966620166868, 6384.499642584082, 6395.264544577247, 6432.549721519636, 6485.967570162119, 6486.578372405778, 4948.901021979468, 4975.0796102721115, 4984.925226121539, 4986.276989836526, 5003.799848006184, 6573.3426657333885, 6628.5691430056895, 6648.202215734143, 6670.0, 6672.933270667231, 6384.553081284265, 6413.454856337077, 6432.9678351607945, 6436.742530298736, 6488.805335839932, 6334.778282760081, 6372.522060845363, 6417.486601071996, 6434.420902118832, 6458.321876202931, 6204.363145663166, 6251.281102673251, 6262.248530176408, 6283.591566548343, 6299.842503937438, 6454.0, 6463.515236357386, 6485.902711459426, 6531.934680653133, 6544.770933017321, 5847.415258474119, 6192.783252586138, 6290.811275661737, 6320.747170113144, 6397.3282805306635], "value": 5039.5808908781755, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [903.4669544968467, 915.9679411220604, 919.0625561926835, 919.5815903763788, 923.8060007398446, 17944.64110717785, 17957.0, 17974.820251797493, 17987.190576424076, 20118.0, 19035.333763318253, 19127.234910603558, 19162.616747665037, 19178.520536986583, 19242.0, 713.4755954373558, 735.8675438421083, 938.2822141062093, 966.4298064142172, 995.7211980645395, 998.9300748947577, 1006.5621454667206, 1007.8236308645958, 1008.2337423558112, 1010.7321559786662, 1061.2730279758352, 1061.9522121504551, 1062.054771253585, 1064.6965614799783, 1064.8881867403927, 1058.9629362972298, 1067.236925598201, 1067.5783065689047, 1067.845162451448, 1068.497806031168, 769.1116760142045, 769.8575763483774, 778.353966208046, 780.6252998560666, 780.7228433905939, 17202.397916072878, 17279.568010799703, 17363.47909562715, 17636.735448968277, 19347.4195774127, 20970.475738106517, 21285.14859405622, 21833.563328733337, 21862.562748744935, 21914.12343506258, 16122.516324510283, 16532.83467165325, 16580.253888575044, 16689.582760430963, 16708.49874503755, 3787.431885217212, 3820.5797362290127, 3822.0, 3823.1971286029966, 3827.2728181645675, 128991.35504322419, 129415.35292323478, 129921.35039324743, 132314.33842830724, 134131.32934335363, 13798.379072941541, 13855.376508057161, 13900.721985560232, 14156.575302740934, 14210.786838197435, 29432.705672942997, 30408.54387184194, 30452.238694032825, 30878.0736577903, 31536.526952095737, 7959.403044771665, 8067.959660201779, 8094.392920530984, 8094.959525202336, 8100.756977290691, 3348.748843836722, 3543.0, 3808.9047773805323, 3940.6256405641993, 4148.543660197376, 5189.351331083614, 5312.893742125136, 5325.680459172462, 5329.840104796862, 5334.7065911374475, 1082.0423924826428, 1084.6637542361893, 1119.0767616716241, 1119.7536541960756, 1122.1303489795473, 603.3574243430733, 619.4827319188438, 631.2961048431058, 648.4423395879539, 653.8398092467376, 11789.764204715857, 12015.639530814089, 12100.878991210151, 13637.863621363858, 14069.366878490493, 678.1895634716565, 698.6052880122816, 703.6446594469862, 717.9030830837875, 739.9186089530148, 5526.226328314039, 5711.228984087178, 5819.399665092134, 5873.148393482921, 6045.576809623372, 113826.0, 115172.0, 115862.42068789771, 116284.0, 116561.0, 100401.49799250957, 101417.0, 101442.49278753706, 103401.48299258456, 107480.46259768808, 86859.56570217108, 87043.56478217569, 87703.56148219218, 88087.11912880918, 90539.54730226306, 10750.51622676997, 10831.67504974852, 10833.349999000086, 10848.294860834021, 11261.380624065427, 41204.175916480905, 41208.0, 41918.0, 42305.78847105745, 42968.57031429771, 50642.0, 53244.201336980754, 54045.0, 67187.0, 71296.64351678209, 18768.62462750765, 19007.42977710671, 19017.239310427427, 19096.71354929705, 19225.615487689887, 17506.124693765516, 18222.271109155485, 19521.0, 22689.978950946916, 22901.31296061121, 16186.0, 17788.288468461633, 18428.447146585622, 18956.146973385956, 19235.807641923402, 18898.14958326906, 18922.905385472983, 19380.515487112934, 19479.0, 20155.093020813805, 18304.0, 19183.904080479508, 19295.131719072393, 19704.507387315432, 19854.702179467607, 13714.45142194341, 14160.787588186391, 14378.568642940729, 14383.065100768417, 14702.117872927658, 13963.371648276056, 14010.509632162826, 14149.504767333094, 14156.716865662835, 14297.0], "value": 9066.209338719242, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1494.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 108.0, 100.0, 107.0, 108.0, 119.0, 108.0, 100.0, 110.0, 110.0], "value": 107.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 116.0, 147.0, 179.0, 119.0, 146.0, 144.0, 121.0, 169.0, 118.0], "value": 140.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 164.0, 163.0, 163.0, 164.0, 163.0, 176.0, 162.0, 165.0], "value": 164.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 74.0, 171.0, 75.0, 75.0, 74.0, 73.0, 151.0, 75.0], "value": 91.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 126.0, 80.0, 79.0, 80.0, 123.0, 81.0, 80.0, 138.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 113.0, 114.0, 114.0, 115.0, 115.0, 115.0, 113.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 151.0, 152.0, 149.0, 149.0, 150.0, 150.0, 149.0, 150.0, 151.0], "value": 150.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 96.0, 94.0, 94.0, 94.0, 95.0, 97.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 52.0, 51.0, 52.0, 53.0, 52.0, 52.0, 53.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 38.0, 39.0, 37.0, 38.0, 38.0, 38.0, 38.0], "value": 38.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 74.0, 75.0, 76.0, 74.0, 75.0, 78.0, 77.0, 77.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 132.0, 125.0, 135.0, 123.0, 126.0, 124.0, 129.0, 118.0, 168.0], "value": 130.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 180.0, 177.0, 170.0, 171.0, 169.0, 171.0, 172.0, 166.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 70.0, 64.0, 72.0, 65.0, 67.0, 64.0, 66.0, 65.0, 66.0], "value": 67.7, "unit": "score"}]}]} 16:11:24 INFO - Return code: 0 16:11:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:11:24 INFO - # TBPL SUCCESS # 16:11:24 INFO - Running post-action listener: _package_coverage_data 16:11:24 INFO - Running post-action listener: _resource_record_post_action 16:11:24 INFO - [mozharness: 2017-09-02 23:11:24.294084Z] Finished run-tests step (success) 16:11:24 INFO - Running post-run listener: _resource_record_post_run 16:11:24 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 344064; Write bytes: 399691776; Read time: 360; Write time: 368356 16:11:24 INFO - TinderboxPrint: CPU usage
13.1% 16:11:24 INFO - TinderboxPrint: I/O read bytes / time
344,064 / 360 16:11:24 INFO - TinderboxPrint: I/O write bytes / time
399,691,776 / 368,356 16:11:24 INFO - TinderboxPrint: CPU idle
8,238.4 (86.8%) 16:11:24 INFO - TinderboxPrint: CPU user
1,196.0 (12.6%) 16:11:24 INFO - TinderboxPrint: Swap in / out
0 / 0 16:11:24 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 115249152; Read time: 0; Write time: 120936 16:11:24 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:11:24 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 344064; Write bytes: 284442624; Read time: 360; Write time: 247420 16:11:24 INFO - Running post-run listener: _upload_blobber_files 16:11:24 INFO - Blob upload gear active. 16:11:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:11:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:11:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:11:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:11:25 INFO - (blobuploader) - INFO - Open directory for files ... 16:11:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:11:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:11:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:11:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:11:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:11:26 INFO - (blobuploader) - INFO - Done attempting. 16:11:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:11:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:11:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:11:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:11:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:11:27 INFO - (blobuploader) - INFO - Done attempting. 16:11:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:11:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:11:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:11:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:11:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:11:34 INFO - (blobuploader) - INFO - Done attempting. 16:11:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:11:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:11:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:11:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:11:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:11:35 INFO - (blobuploader) - INFO - Done attempting. 16:11:35 INFO - (blobuploader) - INFO - Iteration through files over. 16:11:35 INFO - Return code: 0 16:11:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:11:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:11:35 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2572dd94104320d3ceca82066c044dd21ab1b4103929fb0eec606cc9ae1c02dfa4fa8f6b5deca5bb775492c363e38357da56d0450f72f0c66c70aecc967e1162", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bd6099bba2f57fa098c5aa6dd9853eaaa4404b34eb42511c26e36fe07249c4f015bb52daf594f6bb7342363306dc23b9f35295b131f09224a6c72bd1be10d78", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07176e688514888ef54a51302613b727f67d5af7f68eb11871fb274aac78b7af4cedc27f2397b233851008a19cfdb739fb4c9744bc45dfddd8e7fc617049ca1e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/986ca866a0c342a7d44d8479f52fd0e26f988d08c67a6900290c639b8500d624df170b7d685d909e6f31105477ec027ee225e3be5faeb4eeda510d43e2ef504f"} 16:11:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:11:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:11:35 INFO - Contents: 16:11:35 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2572dd94104320d3ceca82066c044dd21ab1b4103929fb0eec606cc9ae1c02dfa4fa8f6b5deca5bb775492c363e38357da56d0450f72f0c66c70aecc967e1162", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bd6099bba2f57fa098c5aa6dd9853eaaa4404b34eb42511c26e36fe07249c4f015bb52daf594f6bb7342363306dc23b9f35295b131f09224a6c72bd1be10d78", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07176e688514888ef54a51302613b727f67d5af7f68eb11871fb274aac78b7af4cedc27f2397b233851008a19cfdb739fb4c9744bc45dfddd8e7fc617049ca1e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/986ca866a0c342a7d44d8479f52fd0e26f988d08c67a6900290c639b8500d624df170b7d685d909e6f31105477ec027ee225e3be5faeb4eeda510d43e2ef504f"} 16:11:35 INFO - Running post-run listener: copy_logs_to_upload_dir 16:11:35 INFO - Copying logs to upload dir... 16:11:35 INFO - mkdir: /builds/slave/test/build/upload/logs 16:11:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.398608 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-09-02 16:11:35.301854) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 16:11:35.307393) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354 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/2572dd94104320d3ceca82066c044dd21ab1b4103929fb0eec606cc9ae1c02dfa4fa8f6b5deca5bb775492c363e38357da56d0450f72f0c66c70aecc967e1162", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bd6099bba2f57fa098c5aa6dd9853eaaa4404b34eb42511c26e36fe07249c4f015bb52daf594f6bb7342363306dc23b9f35295b131f09224a6c72bd1be10d78", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07176e688514888ef54a51302613b727f67d5af7f68eb11871fb274aac78b7af4cedc27f2397b233851008a19cfdb739fb4c9744bc45dfddd8e7fc617049ca1e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/986ca866a0c342a7d44d8479f52fd0e26f988d08c67a6900290c639b8500d624df170b7d685d909e6f31105477ec027ee225e3be5faeb4eeda510d43e2ef504f"} build_url:https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011484 build_url: 'https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2572dd94104320d3ceca82066c044dd21ab1b4103929fb0eec606cc9ae1c02dfa4fa8f6b5deca5bb775492c363e38357da56d0450f72f0c66c70aecc967e1162", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bd6099bba2f57fa098c5aa6dd9853eaaa4404b34eb42511c26e36fe07249c4f015bb52daf594f6bb7342363306dc23b9f35295b131f09224a6c72bd1be10d78", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07176e688514888ef54a51302613b727f67d5af7f68eb11871fb274aac78b7af4cedc27f2397b233851008a19cfdb739fb4c9744bc45dfddd8e7fc617049ca1e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/986ca866a0c342a7d44d8479f52fd0e26f988d08c67a6900290c639b8500d624df170b7d685d909e6f31105477ec027ee225e3be5faeb4eeda510d43e2ef504f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 16:11:35.338351) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:11:35.338800) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1504392640.394443-1896588354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004495 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:11:35.421876) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 16:11:35.422234) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 16:11:35.422561) ========= ========= Total master_lag: 0.20 =========