builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-003 starttime: 1503709991.76 results: success (0) revision: 31465a03c03d1eec31cd4dd5d6b803724dcb29cd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-25 18:13:11.762106) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-25 18:13:11.762602) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-25 18:13:11.762896) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-25 18:13:11.778842) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 18:13:11.779102) ========= 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=7de08804244eb36560b44971000001c3-1503709990.413982-988804046 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-25 18:13:11-- 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% 37.6M=0s 2017-08-25 18:13:12 (37.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.336826 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 18:13:12.133086) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 18:13:12.133393) ========= 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=7de08804244eb36560b44971000001c3-1503709990.413982-988804046 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015170 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 18:13:12.182057) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-25 18:13:12.182342) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 31465a03c03d1eec31cd4dd5d6b803724dcb29cd --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 31465a03c03d1eec31cd4dd5d6b803724dcb29cd --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=7de08804244eb36560b44971000001c3-1503709990.413982-988804046 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-25 18:13:12,248 truncating revision to first 12 chars 2017-08-25 18:13:12,248 Setting DEBUG logging. 2017-08-25 18:13:12,248 attempt 1/10 2017-08-25 18:13:12,248 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/31465a03c03d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-25 18:13:13,692 unpacking tar archive at: mozilla-central-31465a03c03d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.742564 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-25 18:13:13.942286) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-25 18:13:13.945610) ========= script_repo_revision: 31465a03c03d1eec31cd4dd5d6b803724dcb29cd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-25 18:13:13.945983) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-25 18:13:13.946236) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-25 18:13:13.957996) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-25 18:13:13.958255) ========= /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=7de08804244eb36560b44971000001c3-1503709990.413982-988804046 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:13:14 INFO - MultiFileLogger online at 20170825 18:13:14 in /builds/slave/test 18:13:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:13:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:13:14 INFO - {'append_to_log': False, 18:13:14 INFO - 'base_work_dir': '/builds/slave/test', 18:13:14 INFO - 'blob_upload_branch': 'Firefox', 18:13:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:13:14 INFO - 'branch': 'Firefox', 18:13:14 INFO - 'buildbot_json_path': 'buildprops.json', 18:13:14 INFO - 'code_coverage': False, 18:13:14 INFO - 'config_files': ('talos/linux_config.py',), 18:13:14 INFO - 'default_actions': ('clobber', 18:13:14 INFO - 'read-buildbot-config', 18:13:14 INFO - 'download-and-extract', 18:13:14 INFO - 'populate-webroot', 18:13:14 INFO - 'create-virtualenv', 18:13:14 INFO - 'install', 18:13:14 INFO - 'setup-mitmproxy', 18:13:14 INFO - 'run-tests'), 18:13:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:13:14 INFO - 'disable_ccov_upload': False, 18:13:14 INFO - 'download_minidump_stackwalk': True, 18:13:14 INFO - 'download_symbols': 'ondemand', 18:13:14 INFO - 'enable_stylo': False, 18:13:14 INFO - 'enable_webrender': False, 18:13:14 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:13:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:13:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:13:14 INFO - 'gecko_profile': False, 18:13:14 INFO - 'gecko_profile_interval': 0, 18:13:14 INFO - 'installer_path': 'installer.exe', 18:13:14 INFO - 'log_level': 'info', 18:13:14 INFO - 'log_name': 'talos', 18:13:14 INFO - 'log_to_console': True, 18:13:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:13:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:13:14 INFO - 'opt_config_files': (), 18:13:14 INFO - 'pip_index': False, 18:13:14 INFO - 'suite': 'dromaeojs-e10s', 18:13:14 INFO - 'system_bits': '32', 18:13:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:13:14 INFO - 'title': 'talos-linux64-ix-003', 18:13:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:13:14 INFO - 'use_talos_json': True, 18:13:14 INFO - 'verify': 'False', 18:13:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:13:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:13:14 INFO - 'work_dir': 'build'} 18:13:14 INFO - [mozharness: 2017-08-26 01:13:14.159931Z] Running clobber step. 18:13:14 INFO - Running pre-action listener: _resource_record_pre_action 18:13:14 INFO - Running main action method: clobber 18:13:14 INFO - rmtree: /builds/slave/test/build 18:13:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:13:15 INFO - Running post-action listener: _resource_record_post_action 18:13:15 INFO - [mozharness: 2017-08-26 01:13:15.190427Z] Finished clobber step (success) 18:13:15 INFO - [mozharness: 2017-08-26 01:13:15.190525Z] Running read-buildbot-config step. 18:13:15 INFO - Running pre-action listener: _resource_record_pre_action 18:13:15 INFO - Running main action method: read_buildbot_config 18:13:15 INFO - Using buildbot properties: 18:13:15 INFO - { 18:13:15 INFO - "project": "", 18:13:15 INFO - "product": "firefox", 18:13:15 INFO - "who": "kwierso@gmail.com", 18:13:15 INFO - "installer_path": "public/build/target.tar.bz2", 18:13:15 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 18:13:15 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:13:15 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 18:13:15 INFO - "stage_platform": "linux64-stylo", 18:13:15 INFO - "basedir": "/builds/slave/test", 18:13:15 INFO - "buildnumber": 57, 18:13:15 INFO - "platform": "ubuntu64_hw_stylo", 18:13:15 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:13:15 INFO - "slavebuilddir": "test", 18:13:15 INFO - "taskId": "Qx1R4rsxScWnDWpQMcBdsw", 18:13:15 INFO - "branch": "mozilla-central", 18:13:15 INFO - "script_repo_revision": "production", 18:13:15 INFO - "revision": "31465a03c03d1eec31cd4dd5d6b803724dcb29cd", 18:13:15 INFO - "slavename": "talos-linux64-ix-003", 18:13:15 INFO - "repo_path": "mozilla-central" 18:13:15 INFO - } 18:13:15 INFO - Finding installer, test and symbols from parent task. 18:13:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qx1R4rsxScWnDWpQMcBdsw'}, attempt #1 18:13:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qx1R4rsxScWnDWpQMcBdsw'}, attempt #1 18:13:16 INFO - Task dependencies: DhX5PAXHThCBgtR6MhReYw 18:13:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw'}, attempt #1 18:13:16 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.tar.bz2 18:13:16 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.test_packages.json 18:13:16 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.crashreporter-symbols.zip 18:13:16 INFO - Running post-action listener: _resource_record_post_action 18:13:16 INFO - [mozharness: 2017-08-26 01:13:16.561659Z] Finished read-buildbot-config step (success) 18:13:16 INFO - [mozharness: 2017-08-26 01:13:16.561887Z] Running download-and-extract step. 18:13:16 INFO - Running pre-action listener: _resource_record_pre_action 18:13:16 INFO - Running main action method: download_and_extract 18:13:16 INFO - mkdir: /builds/slave/test/build/tests 18:13: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')]} 18:13:16 INFO - trying https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.test_packages.json 18:13:16 INFO - Downloading https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:13:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:13:17 INFO - Downloaded 989 bytes. 18:13:17 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:13:17 INFO - Using the following test package requirements: 18:13:17 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:13:17 INFO - u'common': [u'target.common.tests.zip'], 18:13:17 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:13:17 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:13:17 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:13:17 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:13:17 INFO - u'mozbase': [u'target.common.tests.zip'], 18:13:17 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:13:17 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:13:17 INFO - u'web-platform': [u'target.common.tests.zip', 18:13:17 INFO - u'target.web-platform.tests.tar.gz'], 18:13:17 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:13:17 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:13:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.common.tests.zip 18:13:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:13:17 INFO - Fetch https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.common.tests.zip into memory 18:13:18 INFO - Content-Length response header: 54979128 18:13:18 INFO - Bytes received: 54979128 18:13:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:13:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.common.tests.zip 18:13:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:13:29 INFO - Fetch https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.common.tests.zip into memory 18:13:30 INFO - Content-Length response header: 54979128 18:13:30 INFO - Bytes received: 54979128 18:13:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.talos.tests.zip 18:13:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:13:44 INFO - Fetch https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.talos.tests.zip into memory 18:13:45 INFO - Content-Length response header: 14496611 18:13:45 INFO - Bytes received: 14496611 18:13:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:45 INFO - trying https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.tar.bz2 18:13:45 INFO - Downloading https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:13:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:13:46 INFO - Downloaded 61145976 bytes. 18:13:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.tar.bz2 18:13:46 INFO - mkdir: /builds/slave/test/properties 18:13:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:13:46 INFO - Writing to file /builds/slave/test/properties/build_url 18:13:46 INFO - Contents: 18:13:46 INFO - build_url:https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.tar.bz2 18:13:46 INFO - Running post-action listener: _resource_record_post_action 18:13:46 INFO - Running post-action listener: find_tests_for_verification 18:13:46 INFO - Running post-action listener: set_extra_try_arguments 18:13:46 INFO - [mozharness: 2017-08-26 01:13:46.375464Z] Finished download-and-extract step (success) 18:13:46 INFO - [mozharness: 2017-08-26 01:13:46.375561Z] Running populate-webroot step. 18:13:46 INFO - Running pre-action listener: _resource_record_pre_action 18:13:46 INFO - Running main action method: populate_webroot 18:13:46 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:13:46 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 18:13:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:13:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:13:46 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 18:13:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:13:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:13:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:13:46 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:13:46 INFO - 'talos_options': ['--stylo'], 18:13:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:13:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:13:46 INFO - 'tests': ['damp', 'tps']}, 18:13:46 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:13:46 INFO - 'talos_options': ['--stylo'], 18:13:46 INFO - 'tests': ['damp', 'tps']}, 18:13:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:13:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:13:46 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 18:13:46 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:13:46 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:13:46 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:13:46 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:13:46 INFO - 'talos_options': ['--stylo'], 18:13:46 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:13:46 INFO - 'other-e10s': {'tests': ['a11yr', 18:13:46 INFO - 'ts_paint', 18:13:46 INFO - 'tpaint', 18:13:46 INFO - 'sessionrestore', 18:13:46 INFO - 'sessionrestore_many_windows', 18:13:46 INFO - 'sessionrestore_no_auto_restore', 18:13:46 INFO - 'tabpaint']}, 18:13:46 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 18:13:46 INFO - 'tests': ['a11yr', 18:13:46 INFO - 'ts_paint', 18:13:46 INFO - 'tpaint', 18:13:46 INFO - 'sessionrestore', 18:13:46 INFO - 'sessionrestore_many_windows', 18:13:46 INFO - 'sessionrestore_no_auto_restore', 18:13:46 INFO - 'tabpaint']}, 18:13:46 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:13:46 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:13:46 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 18:13:46 INFO - 'tests': ['bloom_basic_singleton']}, 18:13:46 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 18:13:46 INFO - 'tests': ['bloom_basic']}, 18:13:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:13:46 INFO - 'tsvgr_opacity', 18:13:46 INFO - 'tart', 18:13:46 INFO - 'tscrollx', 18:13:46 INFO - 'tsvg_static']}, 18:13:46 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 18:13:46 INFO - 'tests': ['tsvgx', 18:13:46 INFO - 'tsvgr_opacity', 18:13:46 INFO - 'tart', 18:13:46 INFO - 'tscrollx', 18:13:46 INFO - 'tsvg_static']}, 18:13:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:13:46 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:13:46 INFO - 'talos_options': ['--stylo'], 18:13:46 INFO - 'tests': ['tp5o']}, 18:13:46 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:13:46 INFO - 'talos_options': ['--mitmproxy', 18:13:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:13:46 INFO - '--firstNonBlankPaint'], 18:13:46 INFO - 'tests': ['tp6_google', 18:13:46 INFO - 'tp6_youtube', 18:13:46 INFO - 'tp6_amazon', 18:13:46 INFO - 'tp6_facebook']}, 18:13:46 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:13:46 INFO - 'talos_options': ['--stylo', 18:13:46 INFO - '--mitmproxy', 18:13:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:13:46 INFO - '--firstNonBlankPaint'], 18:13:46 INFO - 'tests': ['tp6_google', 18:13:46 INFO - 'tp6_youtube', 18:13:46 INFO - 'tp6_amazon', 18:13:46 INFO - 'tp6_facebook']}, 18:13:46 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:13:46 INFO - 'talos_options': ['--stylo', 18:13:46 INFO - '--stylo-threads=1', 18:13:46 INFO - '--mitmproxy', 18:13:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:13:46 INFO - '--firstNonBlankPaint'], 18:13:46 INFO - 'tests': ['tp6_google', 18:13:46 INFO - 'tp6_youtube', 18:13:46 INFO - 'tp6_amazon', 18:13:46 INFO - 'tp6_facebook']}, 18:13:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:13:46 INFO - 'talos_options': ['--xperf_path', 18:13:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:13:46 INFO - 'tests': ['tp5n']}, 18:13:46 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:13:46 INFO - 'talos_options': ['--stylo', 18:13:46 INFO - '--xperf_path', 18:13:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:13:46 INFO - 'tests': ['tp5n']}}} 18:13:46 INFO - Running post-action listener: _resource_record_post_action 18:13:46 INFO - [mozharness: 2017-08-26 01:13:46.389843Z] Finished populate-webroot step (success) 18:13:46 INFO - [mozharness: 2017-08-26 01:13:46.389932Z] Running create-virtualenv step. 18:13:46 INFO - Running pre-action listener: _resource_record_pre_action 18:13:46 INFO - Running main action method: create_virtualenv 18:13:46 INFO - Creating virtualenv /builds/slave/test/build/venv 18:13:46 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:13:46 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:13:46 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:13:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:13:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:13:47 INFO - Installing distribute.............................................................................................................................................................................................done. 18:13:47 INFO - Installing pip...............done. 18:13:47 INFO - Return code: 0 18:13:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:13:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:13:47 INFO - Reading from file tmpfile_stdout 18:13:47 INFO - Output received: 18:13:47 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:13:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:47 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 0xab51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc80a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe23f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe21130>, '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 0xe22030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe224c0>, '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': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:13:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:13:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:13:47 INFO - Using env: {'DISPLAY': ':0', 18:13:47 INFO - 'HOME': '/home/cltbld', 18:13:47 INFO - 'LANG': 'en_US.UTF-8', 18:13:47 INFO - 'LANGUAGE': 'en_US:en', 18:13:47 INFO - 'LOGNAME': 'cltbld', 18:13:47 INFO - 'MAIL': '/var/mail/cltbld', 18:13:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:47 INFO - 'MOZ_NO_REMOTE': '1', 18:13:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:13:47 INFO - 'NO_EM_RESTART': '1', 18:13:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:13:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:47 INFO - 'PWD': '/builds/slave/test', 18:13:47 INFO - 'SHELL': '/bin/bash', 18:13:47 INFO - 'SHLVL': '1', 18:13:47 INFO - 'TERM': 'linux', 18:13:47 INFO - 'TMOUT': '86400', 18:13:47 INFO - 'USER': 'cltbld', 18:13:47 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 18:13:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:47 INFO - '_': '/tools/buildbot/bin/python'} 18:13:48 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:13:48 INFO - Downloading/unpacking pip>=1.5 18:13:48 INFO - Running setup.py egg_info for package pip 18:13:48 INFO - warning: no files found matching 'pip/cacert.pem' 18:13:48 INFO - warning: no files found matching '*.html' under directory 'docs' 18:13:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:13:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:13:48 INFO - Installing collected packages: pip 18:13:48 INFO - Found existing installation: pip 1.1 18:13:48 INFO - Uninstalling pip: 18:13:48 INFO - Successfully uninstalled pip 18:13:48 INFO - Running setup.py install for pip 18:13:48 INFO - warning: no files found matching 'pip/cacert.pem' 18:13:48 INFO - warning: no files found matching '*.html' under directory 'docs' 18:13:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:13:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:13:48 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:13:48 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:13:48 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:13:48 INFO - Successfully installed pip 18:13:48 INFO - Cleaning up... 18:13:48 INFO - Return code: 0 18:13:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xab51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc80a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe23f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe21130>, '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 0xe22030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe224c0>, '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': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:13:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:13:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:13:48 INFO - Using env: {'DISPLAY': ':0', 18:13:48 INFO - 'HOME': '/home/cltbld', 18:13:48 INFO - 'LANG': 'en_US.UTF-8', 18:13:48 INFO - 'LANGUAGE': 'en_US:en', 18:13:48 INFO - 'LOGNAME': 'cltbld', 18:13:48 INFO - 'MAIL': '/var/mail/cltbld', 18:13:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:48 INFO - 'MOZ_NO_REMOTE': '1', 18:13:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:13:48 INFO - 'NO_EM_RESTART': '1', 18:13:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:13:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:48 INFO - 'PWD': '/builds/slave/test', 18:13:48 INFO - 'SHELL': '/bin/bash', 18:13:48 INFO - 'SHLVL': '1', 18:13:48 INFO - 'TERM': 'linux', 18:13:48 INFO - 'TMOUT': '86400', 18:13:48 INFO - 'USER': 'cltbld', 18:13:48 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 18:13:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:48 INFO - '_': '/tools/buildbot/bin/python'} 18:13:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:48 INFO - Downloading/unpacking psutil>=3.1.1 18:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:13:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:13:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:13:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:13:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:13:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:13:50 INFO - Installing collected packages: psutil 18:13:50 INFO - Running setup.py install for psutil 18:13:50 INFO - building 'psutil._psutil_linux' extension 18:13:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:13:50 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:13:50 INFO - building 'psutil._psutil_posix' extension 18:13:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:13:50 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:13:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:13:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:13:50 INFO - Successfully installed psutil 18:13:50 INFO - Cleaning up... 18:13:50 INFO - Return code: 0 18:13:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:13: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')]} 18:13:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13: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')]} 18:13:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:50 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 0xab51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc80a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe23f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe21130>, '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 0xe22030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe224c0>, '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': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:13:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:13:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:13:50 INFO - Using env: {'DISPLAY': ':0', 18:13:50 INFO - 'HOME': '/home/cltbld', 18:13:50 INFO - 'LANG': 'en_US.UTF-8', 18:13:50 INFO - 'LANGUAGE': 'en_US:en', 18:13:50 INFO - 'LOGNAME': 'cltbld', 18:13:50 INFO - 'MAIL': '/var/mail/cltbld', 18:13:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:50 INFO - 'MOZ_NO_REMOTE': '1', 18:13:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:13:50 INFO - 'NO_EM_RESTART': '1', 18:13:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:13:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:50 INFO - 'PWD': '/builds/slave/test', 18:13:50 INFO - 'SHELL': '/bin/bash', 18:13:50 INFO - 'SHLVL': '1', 18:13:50 INFO - 'TERM': 'linux', 18:13:50 INFO - 'TMOUT': '86400', 18:13:50 INFO - 'USER': 'cltbld', 18:13:50 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 18:13:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:50 INFO - '_': '/tools/buildbot/bin/python'} 18:13:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:13:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:13:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:13:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:13:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:13:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:13:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:13:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:13:51 INFO - Installing collected packages: mozsystemmonitor 18:13:51 INFO - Running setup.py install for mozsystemmonitor 18:13:51 INFO - Successfully installed mozsystemmonitor 18:13:51 INFO - Cleaning up... 18:13:52 INFO - Return code: 0 18:13:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:13:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:52 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 0xab51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc80a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe23f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe21130>, '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 0xe22030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe224c0>, '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': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:13:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:13:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:13:52 INFO - Using env: {'DISPLAY': ':0', 18:13:52 INFO - 'HOME': '/home/cltbld', 18:13:52 INFO - 'LANG': 'en_US.UTF-8', 18:13:52 INFO - 'LANGUAGE': 'en_US:en', 18:13:52 INFO - 'LOGNAME': 'cltbld', 18:13:52 INFO - 'MAIL': '/var/mail/cltbld', 18:13:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:52 INFO - 'MOZ_NO_REMOTE': '1', 18:13:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:13:52 INFO - 'NO_EM_RESTART': '1', 18:13:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:13:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:52 INFO - 'PWD': '/builds/slave/test', 18:13:52 INFO - 'SHELL': '/bin/bash', 18:13:52 INFO - 'SHLVL': '1', 18:13:52 INFO - 'TERM': 'linux', 18:13:52 INFO - 'TMOUT': '86400', 18:13:52 INFO - 'USER': 'cltbld', 18:13:52 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 18:13:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:52 INFO - '_': '/tools/buildbot/bin/python'} 18:13:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:52 INFO - Downloading/unpacking jsonschema==2.5.1 18:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:13:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:13:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:13:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:13:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:13:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:13:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:13:53 INFO - Downloading functools32-3.2.3-2.tar.gz 18:13:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:13:53 INFO - warning: no files found matching '*.txt' 18:13:53 INFO - no previously-included directories found matching 'build' 18:13:53 INFO - no previously-included directories found matching 'dist' 18:13:53 INFO - no previously-included directories found matching '.git*' 18:13:53 INFO - Installing collected packages: jsonschema, functools32 18:13:53 INFO - Running setup.py install for functools32 18:13:53 INFO - warning: no files found matching '*.txt' 18:13:53 INFO - no previously-included directories found matching 'build' 18:13:53 INFO - no previously-included directories found matching 'dist' 18:13:53 INFO - no previously-included directories found matching '.git*' 18:13:53 INFO - Successfully installed jsonschema functools32 18:13:53 INFO - Cleaning up... 18:13:53 INFO - Return code: 0 18:13:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:13:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xab51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc80a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe23f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe21130>, '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 0xe22030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe224c0>, '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': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:13:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:13:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:13:53 INFO - Using env: {'DISPLAY': ':0', 18:13:53 INFO - 'HOME': '/home/cltbld', 18:13:53 INFO - 'LANG': 'en_US.UTF-8', 18:13:53 INFO - 'LANGUAGE': 'en_US:en', 18:13:53 INFO - 'LOGNAME': 'cltbld', 18:13:53 INFO - 'MAIL': '/var/mail/cltbld', 18:13:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:53 INFO - 'MOZ_NO_REMOTE': '1', 18:13:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:13:53 INFO - 'NO_EM_RESTART': '1', 18:13:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:13:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:53 INFO - 'PWD': '/builds/slave/test', 18:13:53 INFO - 'SHELL': '/bin/bash', 18:13:53 INFO - 'SHLVL': '1', 18:13:53 INFO - 'TERM': 'linux', 18:13:53 INFO - 'TMOUT': '86400', 18:13:53 INFO - 'USER': 'cltbld', 18:13:53 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 18:13:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:53 INFO - '_': '/tools/buildbot/bin/python'} 18:13:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:13:53 INFO - Cleaning up... 18:13:53 INFO - Return code: 0 18:13:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:13:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xab51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc80a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe23f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe21130>, '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 0xe22030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe224c0>, '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': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:13:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:13:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:13:53 INFO - Using env: {'DISPLAY': ':0', 18:13:53 INFO - 'HOME': '/home/cltbld', 18:13:53 INFO - 'LANG': 'en_US.UTF-8', 18:13:53 INFO - 'LANGUAGE': 'en_US:en', 18:13:53 INFO - 'LOGNAME': 'cltbld', 18:13:53 INFO - 'MAIL': '/var/mail/cltbld', 18:13:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:53 INFO - 'MOZ_NO_REMOTE': '1', 18:13:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:13:53 INFO - 'NO_EM_RESTART': '1', 18:13:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:13:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:53 INFO - 'PWD': '/builds/slave/test', 18:13:53 INFO - 'SHELL': '/bin/bash', 18:13:53 INFO - 'SHLVL': '1', 18:13:53 INFO - 'TERM': 'linux', 18:13:53 INFO - 'TMOUT': '86400', 18:13:53 INFO - 'USER': 'cltbld', 18:13:53 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 18:13:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:53 INFO - '_': '/tools/buildbot/bin/python'} 18:13:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:54 INFO - Downloading/unpacking blobuploader==1.2.4 18:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:13:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:13:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:13:55 INFO - Downloading blobuploader-1.2.4.tar.gz 18:13:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:13:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:13:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:13:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:13:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:13:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:13:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:13:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:13:55 INFO - Downloading docopt-0.6.1.tar.gz 18:13:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:13:55 INFO - Installing collected packages: blobuploader, requests, docopt 18:13:55 INFO - Running setup.py install for blobuploader 18:13:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:13:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:13:55 INFO - Running setup.py install for requests 18:13:56 INFO - Running setup.py install for docopt 18:13:56 INFO - Successfully installed blobuploader requests docopt 18:13:56 INFO - Cleaning up... 18:13:56 INFO - Return code: 0 18:13:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:56 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 0xab51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc80a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe23f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe21130>, '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 0xe22030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe224c0>, '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': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:13:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:13:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:13:56 INFO - Using env: {'DISPLAY': ':0', 18:13:56 INFO - 'HOME': '/home/cltbld', 18:13:56 INFO - 'LANG': 'en_US.UTF-8', 18:13:56 INFO - 'LANGUAGE': 'en_US:en', 18:13:56 INFO - 'LOGNAME': 'cltbld', 18:13:56 INFO - 'MAIL': '/var/mail/cltbld', 18:13:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:56 INFO - 'MOZ_NO_REMOTE': '1', 18:13:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:13:56 INFO - 'NO_EM_RESTART': '1', 18:13:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:13:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:56 INFO - 'PWD': '/builds/slave/test', 18:13:56 INFO - 'SHELL': '/bin/bash', 18:13:56 INFO - 'SHLVL': '1', 18:13:56 INFO - 'TERM': 'linux', 18:13:56 INFO - 'TMOUT': '86400', 18:13:56 INFO - 'USER': 'cltbld', 18:13:56 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 18:13:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:56 INFO - '_': '/tools/buildbot/bin/python'} 18:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:13:56 INFO - Running setup.py (path:/tmp/pip-ZvqX1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:13:56 INFO - Running setup.py (path:/tmp/pip-7UCnXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:13:56 INFO - Running setup.py (path:/tmp/pip-I4JQks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:13:56 INFO - Running setup.py (path:/tmp/pip-GQkcn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:13:56 INFO - Running setup.py (path:/tmp/pip-49ShYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:13:57 INFO - Running setup.py (path:/tmp/pip-E43pJT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:13:57 INFO - Running setup.py (path:/tmp/pip-gFoKXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:13:57 INFO - Running setup.py (path:/tmp/pip-MUNwQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:13:57 INFO - Running setup.py (path:/tmp/pip-fD49ZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:13:57 INFO - Running setup.py (path:/tmp/pip-A7hIgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:13:57 INFO - Running setup.py (path:/tmp/pip-e6G8UD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:13:57 INFO - Running setup.py (path:/tmp/pip-JsdN3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:13:57 INFO - Running setup.py (path:/tmp/pip-X6OzQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:13:57 INFO - Running setup.py (path:/tmp/pip-SVr0hS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:13:57 INFO - Running setup.py (path:/tmp/pip-DPhOIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:13:57 INFO - Running setup.py (path:/tmp/pip-sfzMfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:13:57 INFO - Running setup.py (path:/tmp/pip-soFQdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:13:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:13:57 INFO - Running setup.py install for manifestparser 18:13:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:13:57 INFO - Running setup.py install for mozcrash 18:13:58 INFO - Running setup.py install for mozdebug 18:13:58 INFO - Running setup.py install for mozdevice 18:13:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:13:58 INFO - Running setup.py install for mozfile 18:13:58 INFO - Running setup.py install for mozhttpd 18:13:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:13:58 INFO - Running setup.py install for mozinfo 18:13:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:13:58 INFO - Running setup.py install for mozInstall 18:13:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:13:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:13:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:13:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:13:58 INFO - Running setup.py install for mozleak 18:13:58 INFO - Running setup.py install for mozlog 18:13:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:13:58 INFO - Running setup.py install for moznetwork 18:13:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:13:58 INFO - Running setup.py install for mozprocess 18:13:59 INFO - Running setup.py install for mozprofile 18:13:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:13:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:13:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:13:59 INFO - Running setup.py install for mozrunner 18:13:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:13:59 INFO - Running setup.py install for mozscreenshot 18:13:59 INFO - Running setup.py install for moztest 18:13:59 INFO - Running setup.py install for mozversion 18:13:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:13:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:13:59 INFO - Cleaning up... 18:13:59 INFO - Return code: 0 18:13:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:13:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:59 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 0xab51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc80a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe23f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe21130>, '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 0xe22030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe224c0>, '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': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:13:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:13:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:13:59 INFO - Using env: {'DISPLAY': ':0', 18:13:59 INFO - 'HOME': '/home/cltbld', 18:13:59 INFO - 'LANG': 'en_US.UTF-8', 18:13:59 INFO - 'LANGUAGE': 'en_US:en', 18:13:59 INFO - 'LOGNAME': 'cltbld', 18:13:59 INFO - 'MAIL': '/var/mail/cltbld', 18:13:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:59 INFO - 'MOZ_NO_REMOTE': '1', 18:13:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:13:59 INFO - 'NO_EM_RESTART': '1', 18:13:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:13:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:59 INFO - 'PWD': '/builds/slave/test', 18:13:59 INFO - 'SHELL': '/bin/bash', 18:13:59 INFO - 'SHLVL': '1', 18:13:59 INFO - 'TERM': 'linux', 18:13:59 INFO - 'TMOUT': '86400', 18:13:59 INFO - 'USER': 'cltbld', 18:13:59 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 18:13:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:59 INFO - '_': '/tools/buildbot/bin/python'} 18:13:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:13:59 INFO - Running setup.py (path:/tmp/pip-OmsJb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:13:59 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:13:59 INFO - Running setup.py (path:/tmp/pip-Z0iygR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:13:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:13:59 INFO - Running setup.py (path:/tmp/pip-XICvmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:14:00 INFO - Running setup.py (path:/tmp/pip-DqTcXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:14:00 INFO - Running setup.py (path:/tmp/pip-OF4eee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:14:00 INFO - Running setup.py (path:/tmp/pip-hQ7jA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:14:00 INFO - Running setup.py (path:/tmp/pip-0KfkEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:14:00 INFO - Running setup.py (path:/tmp/pip-USBqz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:14:00 INFO - Running setup.py (path:/tmp/pip-45Pqsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:14:00 INFO - Running setup.py (path:/tmp/pip-IlFKIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:14:00 INFO - Running setup.py (path:/tmp/pip-45Irwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:14:00 INFO - Running setup.py (path:/tmp/pip-J8Gd97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:14:00 INFO - Running setup.py (path:/tmp/pip-eLQPQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:14:00 INFO - Running setup.py (path:/tmp/pip-obr64Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:00 INFO - Running setup.py (path:/tmp/pip-Emo0E3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:14:00 INFO - Running setup.py (path:/tmp/pip-d1yze4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:14:00 INFO - Running setup.py (path:/tmp/pip-qWKXPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:14:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:02 INFO - Downloading blessings-1.6.tar.gz 18:14:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:14:02 INFO - Installing collected packages: blessings 18:14:02 INFO - Running setup.py install for blessings 18:14:02 INFO - Successfully installed blessings 18:14:02 INFO - Cleaning up... 18:14:02 INFO - Return code: 0 18:14:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:14:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:14:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:14:02 INFO - Reading from file tmpfile_stdout 18:14:02 INFO - Current package versions: 18:14:02 INFO - argparse == 1.2.1 18:14:02 INFO - blessings == 1.6 18:14:02 INFO - blobuploader == 1.2.4 18:14:02 INFO - docopt == 0.6.1 18:14:02 INFO - functools32 == 3.2.3-2 18:14:02 INFO - jsonschema == 2.5.1 18:14:02 INFO - manifestparser == 1.1 18:14:02 INFO - mozInstall == 1.13 18:14:02 INFO - mozcrash == 1.0 18:14:02 INFO - mozdebug == 0.1 18:14:02 INFO - mozdevice == 0.51 18:14:02 INFO - mozfile == 1.2 18:14:02 INFO - mozhttpd == 0.7 18:14:02 INFO - mozinfo == 0.10 18:14:02 INFO - mozleak == 0.1 18:14:02 INFO - mozlog == 3.5 18:14:02 INFO - moznetwork == 0.27 18:14:02 INFO - mozprocess == 0.25 18:14:02 INFO - mozprofile == 0.28 18:14:02 INFO - mozrunner == 6.13 18:14:02 INFO - mozscreenshot == 0.1 18:14:02 INFO - mozsystemmonitor == 0.3 18:14:02 INFO - moztest == 0.8 18:14:02 INFO - mozversion == 1.4 18:14:02 INFO - psutil == 3.1.1 18:14:02 INFO - requests == 1.2.3 18:14:02 INFO - wsgiref == 0.1.2 18:14:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:14:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:02 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 0xab51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc80a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe23f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe21130>, '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 0xe22030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe224c0>, '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': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:14:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:14:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:14:02 INFO - Using env: {'DISPLAY': ':0', 18:14:02 INFO - 'HOME': '/home/cltbld', 18:14:02 INFO - 'LANG': 'en_US.UTF-8', 18:14:02 INFO - 'LANGUAGE': 'en_US:en', 18:14:02 INFO - 'LOGNAME': 'cltbld', 18:14:02 INFO - 'MAIL': '/var/mail/cltbld', 18:14:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:02 INFO - 'MOZ_NO_REMOTE': '1', 18:14:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:14:02 INFO - 'NO_EM_RESTART': '1', 18:14:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:14:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:02 INFO - 'PWD': '/builds/slave/test', 18:14:02 INFO - 'SHELL': '/bin/bash', 18:14:02 INFO - 'SHLVL': '1', 18:14:02 INFO - 'TERM': 'linux', 18:14:02 INFO - 'TMOUT': '86400', 18:14:02 INFO - 'USER': 'cltbld', 18:14:02 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 18:14:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:02 INFO - '_': '/tools/buildbot/bin/python'} 18:14:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:14:02 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:14:04 INFO - Installing collected packages: simplejson 18:14:04 INFO - Running setup.py install for simplejson 18:14:04 INFO - building 'simplejson._speedups' extension 18:14:04 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 18:14:04 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 18:14:05 INFO - Successfully installed simplejson 18:14:05 INFO - Cleaning up... 18:14:05 INFO - Return code: 0 18:14:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:05 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 0xab51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc80a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe23f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe21130>, '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 0xe22030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe224c0>, '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': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:14:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:14:05 INFO - Using env: {'DISPLAY': ':0', 18:14:05 INFO - 'HOME': '/home/cltbld', 18:14:05 INFO - 'LANG': 'en_US.UTF-8', 18:14:05 INFO - 'LANGUAGE': 'en_US:en', 18:14:05 INFO - 'LOGNAME': 'cltbld', 18:14:05 INFO - 'MAIL': '/var/mail/cltbld', 18:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:05 INFO - 'MOZ_NO_REMOTE': '1', 18:14:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:14:05 INFO - 'NO_EM_RESTART': '1', 18:14:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:05 INFO - 'PWD': '/builds/slave/test', 18:14:05 INFO - 'SHELL': '/bin/bash', 18:14:05 INFO - 'SHLVL': '1', 18:14:05 INFO - 'TERM': 'linux', 18:14:05 INFO - 'TMOUT': '86400', 18:14:05 INFO - 'USER': 'cltbld', 18:14:05 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 18:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:05 INFO - '_': '/tools/buildbot/bin/python'} 18:14:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:14:05 INFO - Cleaning up... 18:14:05 INFO - Return code: 0 18:14:05 INFO - Running post-action listener: _resource_record_post_action 18:14:05 INFO - Running post-action listener: _start_resource_monitoring 18:14:05 INFO - Starting resource monitoring. 18:14:05 INFO - [mozharness: 2017-08-26 01:14:05.306108Z] Finished create-virtualenv step (success) 18:14:05 INFO - [mozharness: 2017-08-26 01:14:05.306633Z] Running install step. 18:14:05 INFO - Running pre-action listener: _resource_record_pre_action 18:14:05 INFO - Running main action method: install 18:14:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:14:05 INFO - Reading from file tmpfile_stdout 18:14:05 INFO - Detecting whether we're running mozinstall >=1.0... 18:14:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:14:05 INFO - Reading from file tmpfile_stdout 18:14:05 INFO - Output received: 18:14:05 INFO - Usage: mozinstall [options] installer 18:14:05 INFO - Options: 18:14:05 INFO - -h, --help show this help message and exit 18:14:05 INFO - -d DEST, --destination=DEST 18:14:05 INFO - Directory to install application into. [default: 18:14:05 INFO - "/builds/slave/test"] 18:14:05 INFO - --app=APP Application being installed. [default: firefox] 18:14:05 INFO - mkdir: /builds/slave/test/build/application 18:14:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:14:23 INFO - Reading from file tmpfile_stdout 18:14:23 INFO - Output received: 18:14:23 INFO - /builds/slave/test/build/application/firefox/firefox 18:14:23 INFO - Running post-action listener: _resource_record_post_action 18:14:23 INFO - [mozharness: 2017-08-26 01:14:23.344254Z] Finished install step (success) 18:14:23 INFO - [mozharness: 2017-08-26 01:14:23.344536Z] Running setup-mitmproxy step. 18:14:23 INFO - Running pre-action listener: _resource_record_pre_action 18:14:23 INFO - Running main action method: setup_mitmproxy 18:14:23 INFO - Skipping: mitmproxy is not required 18:14:23 INFO - Running post-action listener: _resource_record_post_action 18:14:23 INFO - [mozharness: 2017-08-26 01:14:23.345737Z] Finished setup-mitmproxy step (success) 18:14:23 INFO - [mozharness: 2017-08-26 01:14:23.345962Z] Running run-tests step. 18:14:23 INFO - Running pre-action listener: _resource_record_pre_action 18:14:23 INFO - Running pre-action listener: _set_gcov_prefix 18:14:23 INFO - Running main action method: run_tests 18:14:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:14:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:14:23 INFO - Python 2.7.3 18:14:23 INFO - Return code: 0 18:14:23 INFO - grabbing minidump binary from tooltool 18:14:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:23 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 0xe21130>, '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 0xe22030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe224c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:14:23 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:14:23 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:14:23 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:14:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:14:23 INFO - Return code: 0 18:14:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:14:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:14:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:14:23 INFO - ENV: RUST_BACKTRACE is now full 18:14:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:14:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:14:23 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/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:14:23 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/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-003 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:14:23 INFO - Using env: {'DISPLAY': ':0', 18:14:23 INFO - 'HOME': '/home/cltbld', 18:14:23 INFO - 'LANG': 'en_US.UTF-8', 18:14:23 INFO - 'LANGUAGE': 'en_US:en', 18:14:23 INFO - 'LOGNAME': 'cltbld', 18:14:23 INFO - 'MAIL': '/var/mail/cltbld', 18:14:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:14:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:14:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:23 INFO - 'MOZ_NO_REMOTE': '1', 18:14:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:14:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:14:23 INFO - 'NO_EM_RESTART': '1', 18:14:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:14:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:23 INFO - 'PWD': '/builds/slave/test', 18:14:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:14:23 INFO - 'RUST_BACKTRACE': 'full', 18:14:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:14:23 INFO - 'SHELL': '/bin/bash', 18:14:23 INFO - 'SHLVL': '1', 18:14:23 INFO - 'STYLO_FORCE_ENABLED': '1', 18:14:23 INFO - 'TERM': 'linux', 18:14:23 INFO - 'TMOUT': '86400', 18:14:23 INFO - 'USER': 'cltbld', 18:14:23 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 18:14:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:23 INFO - '_': '/tools/buildbot/bin/python'} 18:14:23 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/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:14:23 INFO - mozversion application_buildid: 20170826002533 18:14:23 INFO - mozversion application_changeset: 31465a03c03d1eec31cd4dd5d6b803724dcb29cd 18:14:23 INFO - mozversion application_display_name: Nightly 18:14:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:14:23 INFO - mozversion application_name: Firefox 18:14:23 INFO - mozversion application_remotingname: firefox 18:14:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:14:23 INFO - mozversion application_vendor: Mozilla 18:14:23 INFO - mozversion application_version: 57.0a1 18:14:23 INFO - mozversion platform_buildid: 20170826002533 18:14:23 INFO - mozversion platform_changeset: 31465a03c03d1eec31cd4dd5d6b803724dcb29cd 18:14:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:14:23 INFO - mozversion platform_version: 57.0a1 18:14:23 INFO - using testdate: 1503710063 18:14:23 INFO - actual date: 1503710063 18:14:23 INFO - starting webserver on 'localhost:43704' 18:14:23 INFO - SUITE-START | Running 2 tests 18:14:23 INFO - TEST-START | dromaeo_css 18:14:23 INFO - Initialising browser for dromaeo_css test... 18:14:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43704/getInfo.html -profile /tmp/tmps43ayO/profile 18:14:23 INFO - TEST-INFO | started process 10375 (/builds/slave/test/build/application/firefox/firefox http://localhost:43704/getInfo.html) 18:14:29 INFO - TEST-INFO | 10375: exit 0 18:14:29 INFO - Browser initialized. 18:14:29 INFO - Running cycle 1/1 for dromaeo_css test... 18:14:29 INFO - Using env: {'DISPLAY': ':0', 18:14:29 INFO - 'HOME': '/home/cltbld', 18:14:29 INFO - 'JSGC_DISABLE_POISONING': '1', 18:14:29 INFO - 'LANG': 'en_US.UTF-8', 18:14:29 INFO - 'LANGUAGE': 'en_US:en', 18:14:29 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:14:29 INFO - 'LOGNAME': 'cltbld', 18:14:29 INFO - 'MAIL': '/var/mail/cltbld', 18:14:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:14:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:14:29 INFO - 'MOZ_CRASHREPORTER': '1', 18:14:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:29 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:14:29 INFO - 'MOZ_NO_REMOTE': '1', 18:14:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:14:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:14:29 INFO - 'NO_EM_RESTART': '1', 18:14:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:14:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:29 INFO - 'PWD': '/builds/slave/test', 18:14:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:14:29 INFO - 'RUST_BACKTRACE': 'full', 18:14:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:14:29 INFO - 'SHELL': '/bin/bash', 18:14:29 INFO - 'SHLVL': '1', 18:14:29 INFO - 'STYLO_FORCE_ENABLED': '1', 18:14:29 INFO - 'TERM': 'linux', 18:14:29 INFO - 'TMOUT': '86400', 18:14:29 INFO - 'USER': 'cltbld', 18:14:29 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 18:14:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:29 INFO - '_': '/tools/buildbot/bin/python'} 18:14:29 INFO - TEST-INFO | started process 10561 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps43ayO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:14:30 INFO - PID 10561 | 18:14:30 INFO - PID 10561 | (/builds/slave/test/build/application/firefox/firefox:10610): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:14:30 INFO - PID 10561 | 18:14:31 INFO - PID 10561 | 18:14:31 INFO - PID 10561 | (/builds/slave/test/build/application/firefox/firefox:10670): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:14:31 INFO - PID 10561 | 18:14:31 INFO - PID 10561 | __metrics Screen width/height:1600/1200 18:14:31 INFO - PID 10561 | colorDepth:24 18:14:31 INFO - PID 10561 | Browser inner width/height: 1024/768 18:14:31 INFO - PID 10561 | __metrics 18:14:40 INFO - PID 10561 | RSS: Main: 174481408 18:14:40 INFO - PID 10561 | 18:17:39 INFO - PID 10561 | Cycle 1(1): loaded http://localhost:43704/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43704/tests/dromaeo/cssquery-ext.html) 18:17:41 INFO - PID 10561 | RSS: Main: 178696192 18:17:41 INFO - PID 10561 | 18:20:43 INFO - PID 10561 | Cycle 1(1): loaded http://localhost:43704/tests/dromaeo/cssquery-ext.html (next: http://localhost:43704/tests/dromaeo/cssquery-jquery.html) 18:20:44 INFO - PID 10561 | RSS: Main: 174825472 18:20:44 INFO - PID 10561 | 18:23:45 INFO - PID 10561 | Cycle 1(1): loaded http://localhost:43704/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43704/tests/dromaeo/cssquery-mootools.html) 18:23:46 INFO - PID 10561 | RSS: Main: 177610752 18:23:46 INFO - PID 10561 | 18:26:48 INFO - PID 10561 | Cycle 1(1): loaded http://localhost:43704/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43704/tests/dromaeo/cssquery-prototype.html) 18:26:48 INFO - PID 10561 | RSS: Main: 175566848 18:26:48 INFO - PID 10561 | 18:29:52 INFO - PID 10561 | Cycle 1(1): loaded http://localhost:43704/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43704/tests/dromaeo/cssquery-yui.html) 18:29:53 INFO - PID 10561 | RSS: Main: 177758208 18:29:53 INFO - PID 10561 | 18:32:54 INFO - PID 10561 | Cycle 1(1): loaded http://localhost:43704/tests/dromaeo/cssquery-yui.html (next: http://localhost:43704/tests/dromaeo/cssquery-dojo.html) 18:32:55 INFO - PID 10561 | RSS: Main: 179474432 18:32:55 INFO - PID 10561 | 18:32:55 INFO - PID 10561 | __start_tp_report 18:32:55 INFO - PID 10561 | _x_x_mozilla_page_load 18:32:55 INFO - PID 10561 | _x_x_mozilla_page_load_details 18:32:55 INFO - PID 10561 | |i|pagename|runs| 18:32:55 INFO - PID 10561 | |0;dojo.html;3318.2865683877963;3336.7330613550916;3337.399268131737;3340.5657264555603;3378.1554611347165;6351.491880649549;6359.650219237946;6372.872542549147;6391.5206359523045;6430.356964303567;6246.219222597176;6322.652254126033;6326.905096423546;6335.018072198816;6370.267419246788;5797.072468405046;5800.245968024151;5814.738336774834;5819.738111784959;5850.795222167225;5809.477147056764;5829.154772557977;5853.7365818538265;5942.316633587149;5949.821505354825;5895.469407753301;5898.262717160355;5906.911396329058;5913.704314784265;5918.674472903988;5822.782294130501;5833.416658334175;5868.530517558585;5873.911891321591;5942.2275104236605;5611.7474713637575;5764.193012978187;5765.106408506646;5826.533877289807;5863.296404431453;6261.46777523911;6313.905291420632;6390;6398.360163983564;6440.5491615586925;6692.196936367618;6768.729250829961;6780.660966951633;6796.150481189851;6889.173299204128;6845.5550389224545;6864.107666003438;6880.380765731058;6888.552244104118;6904.3095690431055;6342.556021078526;6399.168108145957;6404.647744374058;6411.262704788963;6446.645434501147;7006.894896576556;7010.474214433938;7037.366637002592;7110.146782386043;7119.857602847914;6509.1863517060365;6600.042993765973;6670.533062685662;6735.158105236846;6824.146981627297;6697.162854643169;6785.321467853274;6798.116244888133;6811.761588344384;6829.6243706596615;6702.430293425114;6710.731570737214;6787.728490860409;6799.1161149051295;6805.863882722318;6674.899876501856;6735.6295403753265;6738.35985581369;6834.863302733917;6837.623930683761;5344.385395679469;5344.893102137936;5345.572354211654;5346;5355;4669.976650116728;4671.5094915034115;4682.01677647691;4687.062587482517;4687.132880417065;5964.314103878023;5990.73041713124;6049.606775559574;6050.031994880798;6051.062085376773;6798.286179951034;6832.555883867532;6848.794536163923;6926.826829329307;6951;5768.3654797972185;5887.1463637772285;5936.821895343147;6009.45914867664;6017.849553761103;5704.401037891044;5901.76392944286;5919.526437884959;5944.286685597669;5950.7917222896995;6572.474202063823;6650.933490665128;6735;6736.831579210559;6740.662966851638;6830.146231721035;6847.897281540881;6958.060661810692;6959.478039147186;6961.721531138698;6437.420632142942;6476.740930362733;6515.869682606416;6523.608583485006;6545.181852268466;6716;6726.192856857208;6774.119364482585;6781.254062053269;6782.796516104525;6859.519833611799;6910.827229319106;6957.304269573002;6958.095447591781;6968.372846443642;6635.767748128792;6639.070530125883;6648.202215734143;6736.292689267556;6741.59550426976;6538.83652908662;6631.535792494479;6634.071230027704;6718.361755633207;6742.359475849787;6513.055606937062;6528.151340325728;6533.869322613426;6553.737850486118;6565.277819439762;6551.475881929339;6638.20341559016;6664.83337916556;6684.832879177865;6688.632125232964;6419.582727122722;6423.96788016057;6455;6456.741730330734;6460.353964603502 18:32:55 INFO - PID 10561 | |1;ext.html;757.4811254290807;822.6627082896014;859.862422012478;871.8692196170574;883.3198437203357;37448;37666.623333766656;37825.24349513008;37874.24251514968;37961.81019094904;34760.13099672509;35314.470282945775;35397.64602353975;35475.2904941901;35581.82209088951;6793.898091528631;6910.550814197086;6913.686399584088;6913.792586222421;6966;6830.146231721035;6938.41023513002;6939.410150137219;6941.444684425259;6941.548799328053;7408.666610002536;7583.620818959058;7588.658510367047;7591.127020392671;7594.73418430355;7465.738699145531;7489.138749043876;7494.700211991514;7500.887486687704;7509.887351689729;7355.374793142536;7360.4479664024875;7511.812204694872;7527.5859827709455;7537.962310188469;34976.82511587426;35133;35231.29537409238;35289.117772055644;35303.823480882435;3413.2661477782394;3453.1367158210446;3455.568053993251;3456.5679290088738;3457.757956943015;29403;29515.70484295151;29558.408831823457;29598.112056638336;29617.703822961925;5165.819196328148;5193.8961220775755;5199.2461093141665;5212.244224587452;5236.3978142513715;181560;183607;185227;187066;195630;44590.66228013165;44908.77545612251;44930.77534612306;45034.77482612566;45052.54947450549;46101;46102.53897460982;46270;46310.53689463062;46372.3044154338;14474.131552106906;14584.635384115481;14634.926825366018;14635.634109147144;14646.340914658653;11939.940300298444;11967.581134660246;11967.880321196677;11979.760404791856;12018.038556915426;13192.406341714646;13231.603051908458;13277.269750163834;13325.733485330433;13452.92376609869;5387.218853266255;5440.863978400572;5448.42791506894;5560.360558535739;5574.693391863405;987.5161171026145;999.7800483893532;1002.0981116994762;1003.5132960514145;1026.3380119822673;21861.344159675235;21864.12543498274;21940.232091876707;22081.252530705977;22223.44441388978;1091.459727434909;1094.414488248799;1096.660035389032;1096.9561217551368;1099.4278182199532;10820.67537973862;11601.535938562363;11672.066234701204;11755.118366122575;11848.703782405337;226567;227669;227740;227774;228169;47750;48029.279560806615;48063.75968120137;48138.51861481411;48252;1405.4729476446332;1423.259904849493;1425.6578421178842;1429.7926800613852;1438.2449214162439;21511.354659360244;21513.784862151177;21793.455163621038;21836.89081554582;21973.890130549244;34304.142396440286;34342;34343.141421464665;34347.313053739286;34439;192663;193580;193678;194055;194469;27282;30464.086077417713;31189.220269492536;31812;31813;37704.81147594244;39063.41404878986;39121.804390977864;39661.20677586374;39703;29526.852365738036;29719.25701857471;29820.254493637833;30237.697623023487;30517.694823052374;29560.556591651573;29734.25664358408;29793.404131917672;30085.94699185518;30151;33084.1728956778;33120.337593248485;33160;33220.50169247415;33353.83323083466;30485.54271685971;30853.228669283446;30954.22614434567;30984.53523197199;31735.206619833767;22846.120017591093;23347.416314592272;23561.175358862358;23721.406964825328;24018.639720404557 18:32:55 INFO - PID 10561 | |2;jquery.html;553.1868153813892;555.5083750880469;562.356102262909;565.5786439102868;571.5427657873702;21210.6818397724;22051.11795528177;22112.668309975365;22113.557728845415;23255.65116523249;22233.666495002548;22618.54762904741;22785.54428911425;22816.20143294985;22835;6276.937230627704;6427.871442571146;6428.678566071701;6443.194600674916;6453.8709225815455;6292.402221788922;6419.967900160493;6483.740650374003;6542.214934207902;6578.967105164492;6539.836504087412;6541.672916354187;6542.280349161589;6542.705578248991;6543.738250469975;6549.639769812658;6551.967240163816;6558.672066396661;6567.310432404601;6574.802755917306;6047.425494578008;6049.727762250709;6054.182685337467;6063.727132279058;6067.666278354689;27284.454310913672;28145.57781633277;28521.57217641737;28611.42777144466;28694.282642933886;1976.1107501624326;2035.6539388304025;2036.3992622176493;2042.1831267492973;2043.1520918818671;18594.442166735018;19008.42974710761;19068.61862762737;19092.61814763697;19108.713369299472;2361.7874391304695;2405.675233843426;2408.3617841272076;2477.6159695247266;2487;129900.35049824785;132906.99999999904;133154;133174.33412832874;133228.33385833105;13870.167789932637;14473.78289325661;14573.562793116223;14617.196054216907;14804.185769782765;22544.774552254596;22557.66163507549;22574.548509029726;22597.548049038927;22637.43406414853;9476.099770521789;9638.855417168748;9688.273379496424;9716.271279654055;9740.951295243478;7897;8779.20987111163;8872.778180545409;8890.4665720057;8989.325800564984;7628.160902300742;8844.778880528038;8971.955140224258;9018.59416326267;9022.458652480873;2584.9593293078574;2594.9610755838676;2614.307208589725;2692.1788854399597;2755;950.5817440326234;975.2831668723483;975.3124047546463;979.8187335342984;982.5480279071564;18986.810131898503;19333.806661933482;19437.611247775247;19452.22191112368;20054.89972550128;1025.7845852370936;1026.2610920137488;1032.3444612671008;1032.442481060234;1037.1391744851906;10611.93880611948;10879.401632910107;10889.782204356026;11003;11121.05471034971;445328;445457;452237;455282;457498;109985;110704.2323941908;110964.4451777736;111760;112484;1128.7403897103711;1158.1256151605485;1175.7530918507039;1181.8345431639577;1183.355071486042;17052.48842534726;17186.828131719023;17480.91259543694;17842.28630854751;18019.459416217534;54930.725346373016;57159.42840571707;57390.426095738505;58160;58548.414515854296;68682.65658671675;68686.62626747537;69446;69540.30459695339;69558.65220674066;12104.878951210376;12507.374631268582;13871.86128138746;14141.222232776885;14401.63995900111;21652.891735541223;21950;22058.88970555201;22180;22265.220717274817;17796;17894.91052544729;17941.102944852966;17950.55123621868;17984.190711417756;16116.919415402848;16283.837161628706;16389.180540973142;16541.172941353125;16842.831571684128;17448.91275543614;17843.37548185807;17902.46292611224;17949.551261218574;18312.084395780425;13936.72126557443;13937.651558710708;14016.929915350358;14061.507847225297;14076.155430674193;17705.20326585281;17793.73309400386;17892.642147157243;17895.463136105936;18933 18:32:55 INFO - PID 10561 | |3;mootools.html;1317.1175312540597;1362.9250391228481;1377.6211541825996;1377.752004639165;1384.9307534623267;14651.267436628179;15239.847601523981;15266.847331526682;15281.31234094467;15299.15854627995;13789.241591712453;14148;15415;15461.768073478906;15466.304016319264;2668.8265262757955;2747.7801775857884;2748.848813315267;2750.188694335176;2752.215618548714;2655.668041494813;2681.2358477833823;2725.9727402726016;2733.671959364879;2772.36235665797;2677.705452400234;2706.472237913608;2708.580170073632;2710.5527587947963;2711.430599574092;2571.7299683533242;2580.6258092576563;2587.4954383895147;2590.1064132874317;2593.5201987632254;2477.665515155467;2499.762522560372;2502.2118032819603;2511.761382668662;2514.0823599386276;14109;14515.782263266059;14658.193799341032;14667.77998330026;14668.339924703414;7656.349210317129;7896.210378962115;7916.604169791487;7918.6040697965445;7948.165442628557;5385.5960802939935;5422.295101636762;5471.452854714496;5544.390117087117;5563.527100196488;1943.1353047893692;1952.687569988795;1956.1001939108028;1990.0746153038906;2005.6690646043385;24916.50166996668;24949.875250623812;25062.749372506223;25078.87460562722;25150.24549263525;6393.744250230032;6535.0850880876715;6573.835654108686;6726.596404215763;6738.831529211809;16572;16576.419825306297;16580.75128873068;16596.419125330554;16598.170091495376;7596.81007974805;7658;7704.383649308042;7726.150123486412;7770.728024519115;6874.587524748531;6903.9989201565495;6917.7232910684015;6926.896096558556;7139.39315158207;4987.476314986874;5108.2848401223855;5118.360204974379;5124.1032819257225;5148.768305426265;1343.1605246720799;1393.2337214531967;1493.133982290269;1504.6238440389902;1522.5660686704457;853.1468531468531;875.1205038935828;890.9242714369351;893.7631527645179;909.622506659742;7862.567558784322;7924.20757924203;7938.325242354349;7971.40214483916;8004.5197288161735;681.2880539835884;684.0389253099403;686.9072675188786;699.6466784274011;787.959892941312;4740.549647783455;4758.191107511731;4817.373741413594;4939.135651261015;5031.949680503221;19602.313919012766;20154.69767953482;20670.79329206719;20691.27580534674;20714.482137946372;20326.898365508372;20775.68836467454;21031.579368412546;21068.15727370919;21090.261840835497;10634.255602107933;10642.36145831268;10723.89276107229;10773.030427261585;10790.730231744112;7604.733834315773;7719.652615632423;7777.57223352721;7837;7865.174156713462;17599.736003959693;17988.19053142638;18031.819681803016;18185.363512277007;18220.45338639843;18167.727484088013;18411.447656570323;18649.440516784518;18680;18856.24575016984;10657.626983055556;10785.946070269913;10788;10789.892101079202;10801.837972430263;14265.144091354552;14625;14651.413943442143;14655.633609159857;14765.926170369079;11888.227265227733;11905.583304584297;12003.939980300043;12318.568850090203;12544.87455125437;11782;11835.881641183822;11839.58561450345;12209.877901220874;12218.45016974256;9976.60093596248;10144.79710405773;10185.49072546355;10250.38497690141;10250.794984100126;6543.280239173592;6750.1562304711915;6866.279040700854;6994.61529615876;7032.296770322927;6964.756233531802;6971.79084627462;6986.860262794818;7188.424926006011;7267.30960558746 18:32:55 INFO - PID 10561 | |4;prototype.html;495.4277809130456;502.51256281407024;512.7769420302168;526.2474661234434;530.0988319856245;6232.719527621257;6328.936710632892;6367.402417634039;6384.521160912933;6464.288928217892;6197.721102550385;6199.163112979746;6264.96867515663;6276.843078923029;6386.776462823802;4991.425986011619;5018.774155163026;5177.948220517794;5236.554892834095;5314.149736042234;5064.974675126619;5081.872953176182;5100.719460429675;5138.2806407103035;5147.485251474841;5017;5047.167217409123;5086.6947983120945;5090.185570308751;5106.668066575679;5018.899622007576;5043.369578802632;5058.266551350033;5074.238864170367;5075.720835354054;4511.954880451186;4524.276115821453;4548.522405147478;4553.886152846172;4569.109023740372;6231.22109736283;6295.401936815995;6313.242410910674;6316.936830631681;6327.936720632781;5691.943080569183;5700.37295897451;5769.653820770725;5770.038187016393;5798.130280457923;6604.801855944329;6619.139511863427;6634.170728658918;6652.168478940132;6766.695498702521;4927.581155601778;4929.260610908356;4942.036302920932;4944.653874228806;4974.701517908937;6611.834704132435;6842.692078856414;6893.345132212432;6973.232944376115;7062.399696025844;4977.601791856643;5006.724630145377;5043.394792624908;5051.898962020739;5125.948740512622;5071.797128114834;5100.464451232641;5111.156659151273;5113.718745469035;5145.305383773143;5683.60214784961;5768.030088858011;5772.7690892363835;5823.369456552172;5856;6767.492438067066;6787.694553745093;6803.319668033256;6812.965935170292;6815.318468153145;1776.2184638758904;1779.2527138601818;1795.973060404095;1802.5133214032137;1803.7564928734716;1158.7392836611696;1193.5379160528285;1205.5418940802615;1218.1788226601288;1239.1449899569268;5855.414458554111;5881.911771323434;5892.970535147382;5975.878563110034;5990.760369585255;6846.931530684729;6856.794296171123;6858.417034552119;6883.380495755306;6886.48351373649;5808.477237048602;5889.94110058894;5901.970490147608;5940.811837632491;6000.939990600038;3600.7479476436397;3610.801405922647;3621.8913432597064;3644.398674218777;3664.908377290589;6547.214334279917;6564.4420224281475;6662.836875889782;6664.366885145903;6705.798826035227;6868.62222577753;6890.414314783199;6923.307669233036;6948.166220053525;7073.257307982691;6070.939290607037;6499;6500.674966251669;6545.738170473128;6602.900956485657;6757.560758550679;6804.931950680628;6829.863402732017;6853.623050732258;6915.861682766417;6578.703958321791;6729.764458243938;6729.85592449275;6883.242843287335;6893.414059804973;6555.508336874658;6557.803265902031;6652.900206497004;6724.663766811737;6735.932640673531;5031.295618613471;5048.72232027242;5087.109755792795;5106.157484015096;5111.386633603991;6537.1501704778075;6717.596944183365;6770.187577490733;6949.548279361826;6954.408875245661;6637.568558043712;6662.866742665023;6673.666316684244;6747.932520674731;6777.695003724943;6544.44372228366;6581.276059633532;6595.241547222131;6612.206535215805;6759.188897332351;6524.054012168114;6545.37818907203;6604.735810567524;6625.701843416961;6700.296468870698;6747.392734653905;6801.761938332134;6806.583662799635;6810.557313774589;6827.283135270725;6555.3772391622715;6564.212294524687;6566.9343306566325;6569.671516424064;6610.3389661035435 18:32:55 INFO - PID 10561 | |5;yui.html;864.2222000199815;879.9340049496288;896.6054935828236;898.4384759525296;898.7797989492567;18014.459566213;19095.14071866766;19231.615367692655;19242.807571924277;21304.254351097716;15892.602684932883;16112.274947627355;16154.515364539082;17766.378176763817;20591.27930522432;918.8529835226349;937.2501998401252;941.2375975459865;945.0691069296737;947.5972711597578;912.7900582865944;947.3842002698233;967.6177909725654;969.0503306759344;969.7721035556639;992.2260636703347;999.170688328689;1001.2390583156816;1005.0401866217744;1005.9396436213814;993.2848349188573;994.0705440413201;996.8205722969863;999.1457304005116;1002.3584905660347;733.9302766237199;734.2914087905144;734.720806093681;734.9669264883094;735.0297607158553;18058.36795712157;18738.344157954543;18804.905975470036;18881.150348234227;19114.522136946547;21099.261525846676;21361.145554177816;21641.675374869388;21652.89173554138;21671.67492487614;17184.914075429668;17352.47942561725;17564.209610567556;17705.38031168903;17766.289348426046;3740.121071548184;3758.699304055669;3762;3767.171222331083;3768.189839184561;130282.3485882574;131724.34137829347;135239.32380338132;136739;137856;16556.75164872528;16579.834201658072;16739.665206695798;17008.57478563046;17074.231659575347;29828.701712983027;30387.696123038928;30412.391752164833;30573.541396879064;30657.540136897966;8668.8699669505;8770.956145219234;8785.736427907173;8792.8681069784;8803.955980220058;4235.470566179228;4331.566843315643;4364.585364390377;4364.956350436455;4399.186150562092;5295.099833028395;5410.242566040757;5419.376771671229;5427.565794736412;5438.048341540294;1031.29356390871;1051.363924825485;1067.1089640150406;1069;1071.164491696478;689.5345641691838;697.4385619576188;702.3082263969985;710.8436144048301;720.7621484909968;12487.93756031214;12591.496340146292;12688.682782930317;12711.237325760483;12885.484580616858;696.2689176364796;702.7645738677498;708.1643660480682;719.704531842681;723.269497807208;5500.532454741312;5828.388019258002;5850.859082478876;5890.823275301748;5902.8819423611285;108948.45525772321;111154;114665;117359;118020.40989795167;97554;98873.50563247282;101720.49139754254;103289.48355258176;103835;90405.54797225972;93408.53295733478;93713.53143234376;93933.5303323479;94079.52960235155;12086.39568021613;12547.560835370718;12865.871341286842;12933.935330323287;13196.340182991004;40065;40104.598954010085;40273.1945361097;40496.97515124173;41074.794626026676;71636.64181679058;73081;73100;74582.38961636317;76814.61592692001;18791.24835006639;19324.61350773005;19388.12753426071;19500.707489387387;19507.60984780268;21313.573728525032;21393.572128557655;22359.105635774387;22522.436939076655;22654.660180096984;18451.630967380308;18587.907060465153;18636.534086647942;18731;19716.01419928969;19506.804931950497;19854.205831767144;19976.001199939656;19991.800081999576;20259;19185;19244.422667320003;19257.22971081197;19467.318643847964;19706.90146549258;14462.566123016326;14551.781723274371;14613.123212607277;14636.121832690073;14725.484608038667;14232;14236.572902812932;14302;14321.069130506483;15003.924980375465 18:32:55 INFO - PID 10561 | __end_tp_report 18:32:55 INFO - PID 10561 | __start_cc_report 18:32:55 INFO - PID 10561 | _x_x_mozilla_cycle_collect,377 18:32:55 INFO - PID 10561 | __end_cc_report 18:32:55 INFO - PID 10561 | __startTimestamp1503711175188__endTimestamp 18:32:55 INFO - PID 10561 | 18:32:55 INFO - PID 10561 | ------- Summary: start ------- 18:32:55 INFO - PID 10561 | Number of tests: 6 18:32:55 INFO - PID 10561 | 18:32:55 INFO - PID 10561 | [#0] dojo.html Cycles:165 Average:6279.08 Median:6509.19 stddev:734.87 (11.3%) stddev-sans-first:699.46 18:32:55 INFO - PID 10561 | Values: 3318.3 3336.7 3337.4 3340.6 3378.2 6351.5 6359.7 6372.9 6391.5 6430.4 6246.2 6322.7 6326.9 6335.0 6370.3 5797.1 5800.2 5814.7 5819.7 5850.8 5809.5 5829.2 5853.7 5942.3 5949.8 5895.5 5898.3 5906.9 5913.7 5918.7 5822.8 5833.4 5868.5 5873.9 5942.2 5611.7 5764.2 5765.1 5826.5 5863.3 6261.5 6313.9 6390.0 6398.4 6440.5 6692.2 6768.7 6780.7 6796.2 6889.2 6845.6 6864.1 6880.4 6888.6 6904.3 6342.6 6399.2 6404.6 6411.3 6446.6 7006.9 7010.5 7037.4 7110.1 7119.9 6509.2 6600.0 6670.5 6735.2 6824.1 6697.2 6785.3 6798.1 6811.8 6829.6 6702.4 6710.7 6787.7 6799.1 6805.9 6674.9 6735.6 6738.4 6834.9 6837.6 5344.4 5344.9 5345.6 5346.0 5355.0 4670.0 4671.5 4682.0 4687.1 4687.1 5964.3 5990.7 6049.6 6050.0 6051.1 6798.3 6832.6 6848.8 6926.8 6951.0 5768.4 5887.1 5936.8 6009.5 6017.8 5704.4 5901.8 5919.5 5944.3 5950.8 6572.5 6650.9 6735.0 6736.8 6740.7 6830.1 6847.9 6958.1 6959.5 6961.7 6437.4 6476.7 6515.9 6523.6 6545.2 6716.0 6726.2 6774.1 6781.3 6782.8 6859.5 6910.8 6957.3 6958.1 6968.4 6635.8 6639.1 6648.2 6736.3 6741.6 6538.8 6631.5 6634.1 6718.4 6742.4 6513.1 6528.2 6533.9 6553.7 6565.3 6551.5 6638.2 6664.8 6684.8 6688.6 6419.6 6424.0 6455.0 6456.7 6460.4 18:32:55 INFO - PID 10561 | 18:32:55 INFO - PID 10561 | [#1] ext.html Cycles:180 Average:35728.80 Median:33190.25 stddev:52707.38 (158.8%) stddev-sans-first:52789.83 18:32:55 INFO - PID 10561 | Values: 757.5 822.7 859.9 871.9 883.3 37448.0 37666.6 37825.2 37874.2 37961.8 34760.1 35314.5 35397.6 35475.3 35581.8 6793.9 6910.6 6913.7 6913.8 6966.0 6830.1 6938.4 6939.4 6941.4 6941.5 7408.7 7583.6 7588.7 7591.1 7594.7 7465.7 7489.1 7494.7 7500.9 7509.9 7355.4 7360.4 7511.8 7527.6 7538.0 34976.8 35133.0 35231.3 35289.1 35303.8 3413.3 3453.1 3455.6 3456.6 3457.8 29403.0 29515.7 29558.4 29598.1 29617.7 5165.8 5193.9 5199.2 5212.2 5236.4 181560.0 183607.0 185227.0 187066.0 195630.0 44590.7 44908.8 44930.8 45034.8 45052.5 46101.0 46102.5 46270.0 46310.5 46372.3 14474.1 14584.6 14634.9 14635.6 14646.3 11939.9 11967.6 11967.9 11979.8 12018.0 13192.4 13231.6 13277.3 13325.7 13452.9 5387.2 5440.9 5448.4 5560.4 5574.7 987.5 999.8 1002.1 1003.5 1026.3 21861.3 21864.1 21940.2 22081.3 22223.4 1091.5 1094.4 1096.7 1097.0 1099.4 10820.7 11601.5 11672.1 11755.1 11848.7 226567.0 227669.0 227740.0 227774.0 228169.0 47750.0 48029.3 48063.8 48138.5 48252.0 1405.5 1423.3 1425.7 1429.8 1438.2 21511.4 21513.8 21793.5 21836.9 21973.9 34304.1 34342.0 34343.1 34347.3 34439.0 192663.0 193580.0 193678.0 194055.0 194469.0 27282.0 30464.1 31189.2 31812.0 31813.0 37704.8 39063.4 39121.8 39661.2 39703.0 29526.9 29719.3 29820.3 30237.7 30517.7 29560.6 29734.3 29793.4 30085.9 30151.0 33084.2 33120.3 33160.0 33220.5 33353.8 30485.5 30853.2 30954.2 30984.5 31735.2 22846.1 23347.4 23561.2 23721.4 24018.6 18:32:55 INFO - PID 10561 | 18:32:55 INFO - PID 10561 | [#2] jquery.html Cycles:180 Average:32994.45 Median:22551.22 stddev:76335.88 (338.5%) stddev-sans-first:76511.16 18:32:55 INFO - PID 10561 | Values: 553.2 555.5 562.4 565.6 571.5 21210.7 22051.1 22112.7 22113.6 23255.7 22233.7 22618.5 22785.5 22816.2 22835.0 6276.9 6427.9 6428.7 6443.2 6453.9 6292.4 6420.0 6483.7 6542.2 6579.0 6539.8 6541.7 6542.3 6542.7 6543.7 6549.6 6552.0 6558.7 6567.3 6574.8 6047.4 6049.7 6054.2 6063.7 6067.7 27284.5 28145.6 28521.6 28611.4 28694.3 1976.1 2035.7 2036.4 2042.2 2043.2 18594.4 19008.4 19068.6 19092.6 19108.7 2361.8 2405.7 2408.4 2477.6 2487.0 129900.4 132907.0 133154.0 133174.3 133228.3 13870.2 14473.8 14573.6 14617.2 14804.2 22544.8 22557.7 22574.5 22597.5 22637.4 9476.1 9638.9 9688.3 9716.3 9741.0 7897.0 8779.2 8872.8 8890.5 8989.3 7628.2 8844.8 8972.0 9018.6 9022.5 2585.0 2595.0 2614.3 2692.2 2755.0 950.6 975.3 975.3 979.8 982.5 18986.8 19333.8 19437.6 19452.2 20054.9 1025.8 1026.3 1032.3 1032.4 1037.1 10611.9 10879.4 10889.8 11003.0 11121.1 445328.0 445457.0 452237.0 455282.0 457498.0 109985.0 110704.2 110964.4 111760.0 112484.0 1128.7 1158.1 1175.8 1181.8 1183.4 17052.5 17186.8 17480.9 17842.3 18019.5 54930.7 57159.4 57390.4 58160.0 58548.4 68682.7 68686.6 69446.0 69540.3 69558.7 12104.9 12507.4 13871.9 14141.2 14401.6 21652.9 21950.0 22058.9 22180.0 22265.2 17796.0 17894.9 17941.1 17950.6 17984.2 16116.9 16283.8 16389.2 16541.2 16842.8 17448.9 17843.4 17902.5 17949.6 18312.1 13936.7 13937.7 14016.9 14061.5 14076.2 17705.2 17793.7 17892.6 17895.5 18933.0 18:32:55 INFO - PID 10561 | 18:32:55 INFO - PID 10561 | [#3] mootools.html Cycles:180 Average:9112.03 Median:13795.32 stddev:6445.98 (46.7%) stddev-sans-first:6437.46 18:32:55 INFO - PID 10561 | Values: 1317.1 1362.9 1377.6 1377.8 1384.9 14651.3 15239.8 15266.8 15281.3 15299.2 13789.2 14148.0 15415.0 15461.8 15466.3 2668.8 2747.8 2748.8 2750.2 2752.2 2655.7 2681.2 2726.0 2733.7 2772.4 2677.7 2706.5 2708.6 2710.6 2711.4 2571.7 2580.6 2587.5 2590.1 2593.5 2477.7 2499.8 2502.2 2511.8 2514.1 14109.0 14515.8 14658.2 14667.8 14668.3 7656.3 7896.2 7916.6 7918.6 7948.2 5385.6 5422.3 5471.5 5544.4 5563.5 1943.1 1952.7 1956.1 1990.1 2005.7 24916.5 24949.9 25062.7 25078.9 25150.2 6393.7 6535.1 6573.8 6726.6 6738.8 16572.0 16576.4 16580.8 16596.4 16598.2 7596.8 7658.0 7704.4 7726.2 7770.7 6874.6 6904.0 6917.7 6926.9 7139.4 4987.5 5108.3 5118.4 5124.1 5148.8 1343.2 1393.2 1493.1 1504.6 1522.6 853.1 875.1 890.9 893.8 909.6 7862.6 7924.2 7938.3 7971.4 8004.5 681.3 684.0 686.9 699.6 788.0 4740.5 4758.2 4817.4 4939.1 5031.9 19602.3 20154.7 20670.8 20691.3 20714.5 20326.9 20775.7 21031.6 21068.2 21090.3 10634.3 10642.4 10723.9 10773.0 10790.7 7604.7 7719.7 7777.6 7837.0 7865.2 17599.7 17988.2 18031.8 18185.4 18220.5 18167.7 18411.4 18649.4 18680.0 18856.2 10657.6 10785.9 10788.0 10789.9 10801.8 14265.1 14625.0 14651.4 14655.6 14765.9 11888.2 11905.6 12003.9 12318.6 12544.9 11782.0 11835.9 11839.6 12209.9 12218.5 9976.6 10144.8 10185.5 10250.4 10250.8 6543.3 6750.2 6866.3 6994.6 7032.3 6964.8 6971.8 6986.9 7188.4 7267.3 18:32:55 INFO - PID 10561 | 18:32:55 INFO - PID 10561 | [#4] prototype.html Cycles:180 Average:5611.80 Median:6270.91 stddev:1579.20 (25.2%) stddev-sans-first:1536.23 18:32:55 INFO - PID 10561 | Values: 495.4 502.5 512.8 526.2 530.1 6232.7 6328.9 6367.4 6384.5 6464.3 6197.7 6199.2 6265.0 6276.8 6386.8 4991.4 5018.8 5177.9 5236.6 5314.1 5065.0 5081.9 5100.7 5138.3 5147.5 5017.0 5047.2 5086.7 5090.2 5106.7 5018.9 5043.4 5058.3 5074.2 5075.7 4512.0 4524.3 4548.5 4553.9 4569.1 6231.2 6295.4 6313.2 6316.9 6327.9 5691.9 5700.4 5769.7 5770.0 5798.1 6604.8 6619.1 6634.2 6652.2 6766.7 4927.6 4929.3 4942.0 4944.7 4974.7 6611.8 6842.7 6893.3 6973.2 7062.4 4977.6 5006.7 5043.4 5051.9 5125.9 5071.8 5100.5 5111.2 5113.7 5145.3 5683.6 5768.0 5772.8 5823.4 5856.0 6767.5 6787.7 6803.3 6813.0 6815.3 1776.2 1779.3 1796.0 1802.5 1803.8 1158.7 1193.5 1205.5 1218.2 1239.1 5855.4 5881.9 5893.0 5975.9 5990.8 6846.9 6856.8 6858.4 6883.4 6886.5 5808.5 5889.9 5902.0 5940.8 6000.9 3600.7 3610.8 3621.9 3644.4 3664.9 6547.2 6564.4 6662.8 6664.4 6705.8 6868.6 6890.4 6923.3 6948.2 7073.3 6070.9 6499.0 6500.7 6545.7 6602.9 6757.6 6804.9 6829.9 6853.6 6915.9 6578.7 6729.8 6729.9 6883.2 6893.4 6555.5 6557.8 6652.9 6724.7 6735.9 5031.3 5048.7 5087.1 5106.2 5111.4 6537.2 6717.6 6770.2 6949.5 6954.4 6637.6 6662.9 6673.7 6747.9 6777.7 6544.4 6581.3 6595.2 6612.2 6759.2 6524.1 6545.4 6604.7 6625.7 6700.3 6747.4 6801.8 6806.6 6810.6 6827.3 6555.4 6564.2 6566.9 6569.7 6610.3 18:32:55 INFO - PID 10561 | 18:32:55 INFO - PID 10561 | [#5] yui.html Cycles:180 Average:24674.75 Median:21517.62 stddev:33861.97 (157.4%) stddev-sans-first:33909.76 18:32:55 INFO - PID 10561 | Values: 864.2 879.9 896.6 898.4 898.8 18014.5 19095.1 19231.6 19242.8 21304.3 15892.6 16112.3 16154.5 17766.4 20591.3 918.9 937.3 941.2 945.1 947.6 912.8 947.4 967.6 969.1 969.8 992.2 999.2 1001.2 1005.0 1005.9 993.3 994.1 996.8 999.1 1002.4 733.9 734.3 734.7 735.0 735.0 18058.4 18738.3 18804.9 18881.2 19114.5 21099.3 21361.1 21641.7 21652.9 21671.7 17184.9 17352.5 17564.2 17705.4 17766.3 3740.1 3758.7 3762.0 3767.2 3768.2 130282.3 131724.3 135239.3 136739.0 137856.0 16556.8 16579.8 16739.7 17008.6 17074.2 29828.7 30387.7 30412.4 30573.5 30657.5 8668.9 8771.0 8785.7 8792.9 8804.0 4235.5 4331.6 4364.6 4365.0 4399.2 5295.1 5410.2 5419.4 5427.6 5438.0 1031.3 1051.4 1067.1 1069.0 1071.2 689.5 697.4 702.3 710.8 720.8 12487.9 12591.5 12688.7 12711.2 12885.5 696.3 702.8 708.2 719.7 723.3 5500.5 5828.4 5850.9 5890.8 5902.9 108948.5 111154.0 114665.0 117359.0 118020.4 97554.0 98873.5 101720.5 103289.5 103835.0 90405.5 93408.5 93713.5 93933.5 94079.5 12086.4 12547.6 12865.9 12933.9 13196.3 40065.0 40104.6 40273.2 40497.0 41074.8 71636.6 73081.0 73100.0 74582.4 76814.6 18791.2 19324.6 19388.1 19500.7 19507.6 21313.6 21393.6 22359.1 22522.4 22654.7 18451.6 18587.9 18636.5 18731.0 19716.0 19506.8 19854.2 19976.0 19991.8 20259.0 19185.0 19244.4 19257.2 19467.3 19706.9 14462.6 14551.8 14613.1 14636.1 14725.5 14232.0 14236.6 14302.0 14321.1 15003.9 18:32:55 INFO - PID 10561 | -------- Summary: end -------- 18:32:55 INFO - PID 10561 | 18:32:55 INFO - TEST-INFO | 10561: exit 0 18:32:56 INFO - TEST-OK | dromaeo_css | took 1112287ms 18:32:56 INFO - TEST-START | kraken 18:32:56 INFO - Initialising browser for kraken test... 18:32:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43704/getInfo.html -profile /tmp/tmpKeayhV/profile 18:32:56 INFO - TEST-INFO | started process 10984 (/builds/slave/test/build/application/firefox/firefox http://localhost:43704/getInfo.html) 18:33:01 INFO - TEST-INFO | 10984: exit 0 18:33:01 INFO - Browser initialized. 18:33:01 INFO - Running cycle 1/1 for kraken test... 18:33:01 INFO - Using env: {'DISPLAY': ':0', 18:33:01 INFO - 'HOME': '/home/cltbld', 18:33:01 INFO - 'JSGC_DISABLE_POISONING': '1', 18:33:01 INFO - 'LANG': 'en_US.UTF-8', 18:33:01 INFO - 'LANGUAGE': 'en_US:en', 18:33:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:33:01 INFO - 'LOGNAME': 'cltbld', 18:33:01 INFO - 'MAIL': '/var/mail/cltbld', 18:33:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:33:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:33:01 INFO - 'MOZ_CRASHREPORTER': '1', 18:33:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:33:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:33:01 INFO - 'MOZ_NO_REMOTE': '1', 18:33:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:33:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:33:01 INFO - 'NO_EM_RESTART': '1', 18:33:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:33:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:33:01 INFO - 'PWD': '/builds/slave/test', 18:33:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:33:01 INFO - 'RUST_BACKTRACE': 'full', 18:33:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:33:01 INFO - 'SHELL': '/bin/bash', 18:33:01 INFO - 'SHLVL': '1', 18:33:01 INFO - 'STYLO_FORCE_ENABLED': '1', 18:33:01 INFO - 'TERM': 'linux', 18:33:01 INFO - 'TMOUT': '86400', 18:33:01 INFO - 'USER': 'cltbld', 18:33:01 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1503709990.413982-988804046', 18:33:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:33:01 INFO - '_': '/tools/buildbot/bin/python'} 18:33:01 INFO - TEST-INFO | started process 11170 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKeayhV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:33:02 INFO - PID 11170 | 18:33:02 INFO - PID 11170 | (/builds/slave/test/build/application/firefox/firefox:11220): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:33:02 INFO - PID 11170 | 18:33:03 INFO - PID 11170 | 18:33:03 INFO - PID 11170 | (/builds/slave/test/build/application/firefox/firefox:11281): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:33:03 INFO - PID 11170 | 18:33:03 INFO - PID 11170 | __metrics Screen width/height:1600/1200 18:33:03 INFO - PID 11170 | colorDepth:24 18:33:03 INFO - PID 11170 | Browser inner width/height: 1024/768 18:33:03 INFO - PID 11170 | __metrics 18:33:13 INFO - PID 11170 | RSS: Main: 174485504 18:33:13 INFO - PID 11170 | 18:33:15 INFO - PID 11170 | Cycle 1(1): loaded http://localhost:43704/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43704/tests/kraken/driver.html?testName=audio-beat-detection) 18:33:16 INFO - PID 11170 | RSS: Main: 179007488 18:33:16 INFO - PID 11170 | 18:33:19 INFO - PID 11170 | Cycle 1(1): loaded http://localhost:43704/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43704/tests/kraken/driver.html?testName=audio-dft) 18:33:19 INFO - PID 11170 | RSS: Main: 179228672 18:33:19 INFO - PID 11170 | 18:33:23 INFO - PID 11170 | Cycle 1(1): loaded http://localhost:43704/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43704/tests/kraken/driver.html?testName=audio-fft) 18:33:23 INFO - PID 11170 | RSS: Main: 173973504 18:33:23 INFO - PID 11170 | 18:33:25 INFO - PID 11170 | Cycle 1(1): loaded http://localhost:43704/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43704/tests/kraken/driver.html?testName=audio-oscillator) 18:33:26 INFO - PID 11170 | RSS: Main: 174567424 18:33:26 INFO - PID 11170 | 18:33:28 INFO - PID 11170 | Cycle 1(1): loaded http://localhost:43704/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43704/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:33:29 INFO - PID 11170 | RSS: Main: 174825472 18:33:29 INFO - PID 11170 | 18:33:32 INFO - PID 11170 | Cycle 1(1): loaded http://localhost:43704/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43704/tests/kraken/driver.html?testName=imaging-darkroom) 18:33:33 INFO - PID 11170 | RSS: Main: 175722496 18:33:33 INFO - PID 11170 | 18:33:36 INFO - PID 11170 | Cycle 1(1): loaded http://localhost:43704/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43704/tests/kraken/driver.html?testName=imaging-desaturate) 18:33:37 INFO - PID 11170 | RSS: Main: 175599616 18:33:37 INFO - PID 11170 | 18:33:40 INFO - PID 11170 | Cycle 1(1): loaded http://localhost:43704/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43704/tests/kraken/driver.html?testName=json-parse-financial) 18:33:40 INFO - PID 11170 | RSS: Main: 175968256 18:33:40 INFO - PID 11170 | 18:33:42 INFO - PID 11170 | Cycle 1(1): loaded http://localhost:43704/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43704/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:33:43 INFO - PID 11170 | RSS: Main: 176586752 18:33:43 INFO - PID 11170 | 18:33:45 INFO - PID 11170 | Cycle 1(1): loaded http://localhost:43704/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43704/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:33:45 INFO - PID 11170 | RSS: Main: 176209920 18:33:45 INFO - PID 11170 | 18:33:47 INFO - PID 11170 | Cycle 1(1): loaded http://localhost:43704/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43704/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:33:48 INFO - PID 11170 | RSS: Main: 176832512 18:33:48 INFO - PID 11170 | 18:33:51 INFO - PID 11170 | Cycle 1(1): loaded http://localhost:43704/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43704/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:33:51 INFO - PID 11170 | RSS: Main: 176537600 18:33:51 INFO - PID 11170 | 18:33:54 INFO - PID 11170 | Cycle 1(1): loaded http://localhost:43704/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43704/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:33:55 INFO - PID 11170 | RSS: Main: 176693248 18:33:55 INFO - PID 11170 | 18:33:57 INFO - PID 11170 | Cycle 1(1): loaded http://localhost:43704/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43704/tests/kraken/driver.html?testName=ai-astar) 18:33:57 INFO - PID 11170 | RSS: Main: 178151424 18:33:57 INFO - PID 11170 | 18:33:57 INFO - PID 11170 | __start_tp_report 18:33:57 INFO - PID 11170 | _x_x_mozilla_page_load 18:33:57 INFO - PID 11170 | _x_x_mozilla_page_load_details 18:33:57 INFO - PID 11170 | |i|pagename|runs| 18:33:57 INFO - PID 11170 | |0;ai-astar;103;107;99;105;106;111;114;97;106;114 18:33:57 INFO - PID 11170 | |1;audio-beat-detection;142;120;149;173;118;151;160;117;171;119 18:33:57 INFO - PID 11170 | |2;audio-dft;163;162;171;163;165;170;173;166;164;162 18:33:57 INFO - PID 11170 | |3;audio-fft;73;74;77;154;75;73;74;89;95;74 18:33:57 INFO - PID 11170 | |4;audio-oscillator;78;79;136;79;79;78;132;78;82;129 18:33:57 INFO - PID 11170 | |5;imaging-gaussian-blur;114;113;114;121;126;121;116;114;119;114 18:33:57 INFO - PID 11170 | |6;imaging-darkroom;149;147;147;149;149;148;147;147;149;153 18:33:57 INFO - PID 11170 | |7;imaging-desaturate;104;98;94;95;93;94;94;96;94;94 18:33:57 INFO - PID 11170 | |8;json-parse-financial;53;51;57;53;53;58;51;56;52;54 18:33:57 INFO - PID 11170 | |9;json-stringify-tinderbox;59;63;54;56;55;55;55;55;55;56 18:33:57 INFO - PID 11170 | |10;stanford-crypto-aes;76;76;77;81;78;73;79;90;74;74 18:33:57 INFO - PID 11170 | |11;stanford-crypto-ccm;120;122;118;119;116;118;122;129;114;153 18:33:57 INFO - PID 11170 | |12;stanford-crypto-pbkdf2;177;179;184;170;171;182;174;172;170;169 18:33:57 INFO - PID 11170 | |13;stanford-crypto-sha256-iterative;64;64;66;63;63;62;65;66;64;63 18:33:57 INFO - PID 11170 | __end_tp_report 18:33:57 INFO - PID 11170 | __start_cc_report 18:33:57 INFO - PID 11170 | _x_x_mozilla_cycle_collect,944 18:33:57 INFO - PID 11170 | __end_cc_report 18:33:57 INFO - PID 11170 | __startTimestamp1503711237523__endTimestamp 18:33:57 INFO - PID 11170 | 18:33:57 INFO - PID 11170 | ------- Summary: start ------- 18:33:57 INFO - PID 11170 | Number of tests: 14 18:33:57 INFO - PID 11170 | 18:33:57 INFO - PID 11170 | [#0] ai-astar Cycles:10 Average:106.20 Median:112.50 stddev:5.71 (5.1%) stddev-sans-first:5.94 18:33:57 INFO - PID 11170 | Values: 103.0 107.0 99.0 105.0 106.0 111.0 114.0 97.0 106.0 114.0 18:33:57 INFO - PID 11170 | 18:33:57 INFO - PID 11170 | [#1] audio-beat-detection Cycles:10 Average:142.00 Median:150.00 stddev:22.29 (14.9%) stddev-sans-first:23.64 18:33:57 INFO - PID 11170 | Values: 142.0 120.0 149.0 173.0 118.0 151.0 160.0 117.0 171.0 119.0 18:33:57 INFO - PID 11170 | 18:33:57 INFO - PID 11170 | [#2] audio-dft Cycles:10 Average:165.90 Median:165.50 stddev:4.01 (2.4%) stddev-sans-first:4.12 18:33:57 INFO - PID 11170 | Values: 163.0 162.0 171.0 163.0 165.0 170.0 173.0 166.0 164.0 162.0 18:33:57 INFO - PID 11170 | 18:33:57 INFO - PID 11170 | [#3] audio-fft Cycles:10 Average:85.80 Median:74.50 stddev:25.13 (33.7%) stddev-sans-first:26.23 18:33:57 INFO - PID 11170 | Values: 73.0 74.0 77.0 154.0 75.0 73.0 74.0 89.0 95.0 74.0 18:33:57 INFO - PID 11170 | 18:33:57 INFO - PID 11170 | [#4] audio-oscillator Cycles:10 Average:95.00 Median:78.50 stddev:25.84 (32.9%) stddev-sans-first:26.67 18:33:57 INFO - PID 11170 | Values: 78.0 79.0 136.0 79.0 79.0 78.0 132.0 78.0 82.0 129.0 18:33:57 INFO - PID 11170 | 18:33:57 INFO - PID 11170 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.50 stddev:4.34 (3.7%) stddev-sans-first:4.45 18:33:57 INFO - PID 11170 | Values: 114.0 113.0 114.0 121.0 126.0 121.0 116.0 114.0 119.0 114.0 18:33:57 INFO - PID 11170 | 18:33:57 INFO - PID 11170 | [#6] imaging-darkroom Cycles:10 Average:148.50 Median:149.00 stddev:1.84 (1.2%) stddev-sans-first:1.94 18:33:57 INFO - PID 11170 | Values: 149.0 147.0 147.0 149.0 149.0 148.0 147.0 147.0 149.0 153.0 18:33:57 INFO - PID 11170 | 18:33:57 INFO - PID 11170 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:94.00 stddev:3.27 (3.5%) stddev-sans-first:1.50 18:33:57 INFO - PID 11170 | Values: 104.0 98.0 94.0 95.0 93.0 94.0 94.0 96.0 94.0 94.0 18:33:57 INFO - PID 11170 | 18:33:57 INFO - PID 11170 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:53.50 stddev:2.44 (4.6%) stddev-sans-first:2.57 18:33:57 INFO - PID 11170 | Values: 53.0 51.0 57.0 53.0 53.0 58.0 51.0 56.0 52.0 54.0 18:33:57 INFO - PID 11170 | 18:33:57 INFO - PID 11170 | [#9] json-stringify-tinderbox Cycles:10 Average:56.30 Median:55.50 stddev:2.71 (4.9%) stddev-sans-first:2.69 18:33:57 INFO - PID 11170 | Values: 59.0 63.0 54.0 56.0 55.0 55.0 55.0 55.0 55.0 56.0 18:33:57 INFO - PID 11170 | 18:33:57 INFO - PID 11170 | [#10] stanford-crypto-aes Cycles:10 Average:77.80 Median:77.50 stddev:4.94 (6.4%) stddev-sans-first:5.20 18:33:57 INFO - PID 11170 | Values: 76.0 76.0 77.0 81.0 78.0 73.0 79.0 90.0 74.0 74.0 18:33:57 INFO - PID 11170 | 18:33:57 INFO - PID 11170 | [#11] stanford-crypto-ccm Cycles:10 Average:123.10 Median:121.00 stddev:11.27 (9.3%) stddev-sans-first:11.90 18:33:57 INFO - PID 11170 | Values: 120.0 122.0 118.0 119.0 116.0 118.0 122.0 129.0 114.0 153.0 18:33:57 INFO - PID 11170 | 18:33:57 INFO - PID 11170 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.80 Median:175.50 stddev:5.39 (3.1%) stddev-sans-first:5.66 18:33:57 INFO - PID 11170 | Values: 177.0 179.0 184.0 170.0 171.0 182.0 174.0 172.0 170.0 169.0 18:33:57 INFO - PID 11170 | 18:33:57 INFO - PID 11170 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.00 Median:64.00 stddev:1.33 (2.1%) stddev-sans-first:1.41 18:33:57 INFO - PID 11170 | Values: 64.0 64.0 66.0 63.0 63.0 62.0 65.0 66.0 64.0 63.0 18:33:57 INFO - PID 11170 | -------- Summary: end -------- 18:33:57 INFO - PID 11170 | 18:33:57 INFO - PID 11170 | 18:33:57 INFO - PID 11170 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150082,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 18:33:57 INFO - PID 11170 | 18:33:57 INFO - PID 11170 | 18:33:57 INFO - PID 11170 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 18:33:57 INFO - PID 11170 | 18:33:57 INFO - PID 11170 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:33:58 INFO - TEST-INFO | 11170: exit 0 18:33:58 INFO - TEST-OK | kraken | took 62277ms 18:33:58 INFO - SUITE-END | took 1174s 18:33:58 INFO - Completed test suite (00:19:34) 18:33:58 INFO - javascript benchmark 18:33:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8448.79385923918, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3318.2865683877963, 3336.7330613550916, 3337.399268131737, 3340.5657264555603, 3378.1554611347165, 6351.491880649549, 6359.650219237946, 6372.872542549147, 6391.5206359523045, 6430.356964303567, 6246.219222597176, 6322.652254126033, 6326.905096423546, 6335.018072198816, 6370.267419246788, 5797.072468405046, 5800.245968024151, 5814.738336774834, 5819.738111784959, 5850.795222167225, 5809.477147056764, 5829.154772557977, 5853.7365818538265, 5942.316633587149, 5949.821505354825, 5895.469407753301, 5898.262717160355, 5906.911396329058, 5913.704314784265, 5918.674472903988, 5822.782294130501, 5833.416658334175, 5868.530517558585, 5873.911891321591, 5942.2275104236605, 5611.7474713637575, 5764.193012978187, 5765.106408506646, 5826.533877289807, 5863.296404431453, 6261.46777523911, 6313.905291420632, 6390.0, 6398.360163983564, 6440.5491615586925, 6692.196936367618, 6768.729250829961, 6780.660966951633, 6796.150481189851, 6889.173299204128, 6845.5550389224545, 6864.107666003438, 6880.380765731058, 6888.552244104118, 6904.3095690431055, 6342.556021078526, 6399.168108145957, 6404.647744374058, 6411.262704788963, 6446.645434501147, 7006.894896576556, 7010.474214433938, 7037.366637002592, 7110.146782386043, 7119.857602847914, 6509.1863517060365, 6600.042993765973, 6670.533062685662, 6735.158105236846, 6824.146981627297, 6697.162854643169, 6785.321467853274, 6798.116244888133, 6811.761588344384, 6829.6243706596615, 6702.430293425114, 6710.731570737214, 6787.728490860409, 6799.1161149051295, 6805.863882722318, 6674.899876501856, 6735.6295403753265, 6738.35985581369, 6834.863302733917, 6837.623930683761, 5344.385395679469, 5344.893102137936, 5345.572354211654, 5346.0, 5355.0, 4669.976650116728, 4671.5094915034115, 4682.01677647691, 4687.062587482517, 4687.132880417065, 5964.314103878023, 5990.73041713124, 6049.606775559574, 6050.031994880798, 6051.062085376773, 6798.286179951034, 6832.555883867532, 6848.794536163923, 6926.826829329307, 6951.0, 5768.3654797972185, 5887.1463637772285, 5936.821895343147, 6009.45914867664, 6017.849553761103, 5704.401037891044, 5901.76392944286, 5919.526437884959, 5944.286685597669, 5950.7917222896995, 6572.474202063823, 6650.933490665128, 6735.0, 6736.831579210559, 6740.662966851638, 6830.146231721035, 6847.897281540881, 6958.060661810692, 6959.478039147186, 6961.721531138698, 6437.420632142942, 6476.740930362733, 6515.869682606416, 6523.608583485006, 6545.181852268466, 6716.0, 6726.192856857208, 6774.119364482585, 6781.254062053269, 6782.796516104525, 6859.519833611799, 6910.827229319106, 6957.304269573002, 6958.095447591781, 6968.372846443642, 6635.767748128792, 6639.070530125883, 6648.202215734143, 6736.292689267556, 6741.59550426976, 6538.83652908662, 6631.535792494479, 6634.071230027704, 6718.361755633207, 6742.359475849787, 6513.055606937062, 6528.151340325728, 6533.869322613426, 6553.737850486118, 6565.277819439762, 6551.475881929339, 6638.20341559016, 6664.83337916556, 6684.832879177865, 6688.632125232964, 6419.582727122722, 6423.96788016057, 6455.0, 6456.741730330734, 6460.353964603502], "value": 6225.508609563722, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [757.4811254290807, 822.6627082896014, 859.862422012478, 871.8692196170574, 883.3198437203357, 37448.0, 37666.623333766656, 37825.24349513008, 37874.24251514968, 37961.81019094904, 34760.13099672509, 35314.470282945775, 35397.64602353975, 35475.2904941901, 35581.82209088951, 6793.898091528631, 6910.550814197086, 6913.686399584088, 6913.792586222421, 6966.0, 6830.146231721035, 6938.41023513002, 6939.410150137219, 6941.444684425259, 6941.548799328053, 7408.666610002536, 7583.620818959058, 7588.658510367047, 7591.127020392671, 7594.73418430355, 7465.738699145531, 7489.138749043876, 7494.700211991514, 7500.887486687704, 7509.887351689729, 7355.374793142536, 7360.4479664024875, 7511.812204694872, 7527.5859827709455, 7537.962310188469, 34976.82511587426, 35133.0, 35231.29537409238, 35289.117772055644, 35303.823480882435, 3413.2661477782394, 3453.1367158210446, 3455.568053993251, 3456.5679290088738, 3457.757956943015, 29403.0, 29515.70484295151, 29558.408831823457, 29598.112056638336, 29617.703822961925, 5165.819196328148, 5193.8961220775755, 5199.2461093141665, 5212.244224587452, 5236.3978142513715, 181560.0, 183607.0, 185227.0, 187066.0, 195630.0, 44590.66228013165, 44908.77545612251, 44930.77534612306, 45034.77482612566, 45052.54947450549, 46101.0, 46102.53897460982, 46270.0, 46310.53689463062, 46372.3044154338, 14474.131552106906, 14584.635384115481, 14634.926825366018, 14635.634109147144, 14646.340914658653, 11939.940300298444, 11967.581134660246, 11967.880321196677, 11979.760404791856, 12018.038556915426, 13192.406341714646, 13231.603051908458, 13277.269750163834, 13325.733485330433, 13452.92376609869, 5387.218853266255, 5440.863978400572, 5448.42791506894, 5560.360558535739, 5574.693391863405, 987.5161171026145, 999.7800483893532, 1002.0981116994762, 1003.5132960514145, 1026.3380119822673, 21861.344159675235, 21864.12543498274, 21940.232091876707, 22081.252530705977, 22223.44441388978, 1091.459727434909, 1094.414488248799, 1096.660035389032, 1096.9561217551368, 1099.4278182199532, 10820.67537973862, 11601.535938562363, 11672.066234701204, 11755.118366122575, 11848.703782405337, 226567.0, 227669.0, 227740.0, 227774.0, 228169.0, 47750.0, 48029.279560806615, 48063.75968120137, 48138.51861481411, 48252.0, 1405.4729476446332, 1423.259904849493, 1425.6578421178842, 1429.7926800613852, 1438.2449214162439, 21511.354659360244, 21513.784862151177, 21793.455163621038, 21836.89081554582, 21973.890130549244, 34304.142396440286, 34342.0, 34343.141421464665, 34347.313053739286, 34439.0, 192663.0, 193580.0, 193678.0, 194055.0, 194469.0, 27282.0, 30464.086077417713, 31189.220269492536, 31812.0, 31813.0, 37704.81147594244, 39063.41404878986, 39121.804390977864, 39661.20677586374, 39703.0, 29526.852365738036, 29719.25701857471, 29820.254493637833, 30237.697623023487, 30517.694823052374, 29560.556591651573, 29734.25664358408, 29793.404131917672, 30085.94699185518, 30151.0, 33084.1728956778, 33120.337593248485, 33160.0, 33220.50169247415, 33353.83323083466, 30485.54271685971, 30853.228669283446, 30954.22614434567, 30984.53523197199, 31735.206619833767, 22846.120017591093, 23347.416314592272, 23561.175358862358, 23721.406964825328, 24018.639720404557], "value": 16184.826185815173, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.1868153813892, 555.5083750880469, 562.356102262909, 565.5786439102868, 571.5427657873702, 21210.6818397724, 22051.11795528177, 22112.668309975365, 22113.557728845415, 23255.65116523249, 22233.666495002548, 22618.54762904741, 22785.54428911425, 22816.20143294985, 22835.0, 6276.937230627704, 6427.871442571146, 6428.678566071701, 6443.194600674916, 6453.8709225815455, 6292.402221788922, 6419.967900160493, 6483.740650374003, 6542.214934207902, 6578.967105164492, 6539.836504087412, 6541.672916354187, 6542.280349161589, 6542.705578248991, 6543.738250469975, 6549.639769812658, 6551.967240163816, 6558.672066396661, 6567.310432404601, 6574.802755917306, 6047.425494578008, 6049.727762250709, 6054.182685337467, 6063.727132279058, 6067.666278354689, 27284.454310913672, 28145.57781633277, 28521.57217641737, 28611.42777144466, 28694.282642933886, 1976.1107501624326, 2035.6539388304025, 2036.3992622176493, 2042.1831267492973, 2043.1520918818671, 18594.442166735018, 19008.42974710761, 19068.61862762737, 19092.61814763697, 19108.713369299472, 2361.7874391304695, 2405.675233843426, 2408.3617841272076, 2477.6159695247266, 2487.0, 129900.35049824785, 132906.99999999904, 133154.0, 133174.33412832874, 133228.33385833105, 13870.167789932637, 14473.78289325661, 14573.562793116223, 14617.196054216907, 14804.185769782765, 22544.774552254596, 22557.66163507549, 22574.548509029726, 22597.548049038927, 22637.43406414853, 9476.099770521789, 9638.855417168748, 9688.273379496424, 9716.271279654055, 9740.951295243478, 7897.0, 8779.20987111163, 8872.778180545409, 8890.4665720057, 8989.325800564984, 7628.160902300742, 8844.778880528038, 8971.955140224258, 9018.59416326267, 9022.458652480873, 2584.9593293078574, 2594.9610755838676, 2614.307208589725, 2692.1788854399597, 2755.0, 950.5817440326234, 975.2831668723483, 975.3124047546463, 979.8187335342984, 982.5480279071564, 18986.810131898503, 19333.806661933482, 19437.611247775247, 19452.22191112368, 20054.89972550128, 1025.7845852370936, 1026.2610920137488, 1032.3444612671008, 1032.442481060234, 1037.1391744851906, 10611.93880611948, 10879.401632910107, 10889.782204356026, 11003.0, 11121.05471034971, 445328.0, 445457.0, 452237.0, 455282.0, 457498.0, 109985.0, 110704.2323941908, 110964.4451777736, 111760.0, 112484.0, 1128.7403897103711, 1158.1256151605485, 1175.7530918507039, 1181.8345431639577, 1183.355071486042, 17052.48842534726, 17186.828131719023, 17480.91259543694, 17842.28630854751, 18019.459416217534, 54930.725346373016, 57159.42840571707, 57390.426095738505, 58160.0, 58548.414515854296, 68682.65658671675, 68686.62626747537, 69446.0, 69540.30459695339, 69558.65220674066, 12104.878951210376, 12507.374631268582, 13871.86128138746, 14141.222232776885, 14401.63995900111, 21652.891735541223, 21950.0, 22058.88970555201, 22180.0, 22265.220717274817, 17796.0, 17894.91052544729, 17941.102944852966, 17950.55123621868, 17984.190711417756, 16116.919415402848, 16283.837161628706, 16389.180540973142, 16541.172941353125, 16842.831571684128, 17448.91275543614, 17843.37548185807, 17902.46292611224, 17949.551261218574, 18312.084395780425, 13936.72126557443, 13937.651558710708, 14016.929915350358, 14061.507847225297, 14076.155430674193, 17705.20326585281, 17793.73309400386, 17892.642147157243, 17895.463136105936, 18933.0], "value": 11627.414157457566, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.1175312540597, 1362.9250391228481, 1377.6211541825996, 1377.752004639165, 1384.9307534623267, 14651.267436628179, 15239.847601523981, 15266.847331526682, 15281.31234094467, 15299.15854627995, 13789.241591712453, 14148.0, 15415.0, 15461.768073478906, 15466.304016319264, 2668.8265262757955, 2747.7801775857884, 2748.848813315267, 2750.188694335176, 2752.215618548714, 2655.668041494813, 2681.2358477833823, 2725.9727402726016, 2733.671959364879, 2772.36235665797, 2677.705452400234, 2706.472237913608, 2708.580170073632, 2710.5527587947963, 2711.430599574092, 2571.7299683533242, 2580.6258092576563, 2587.4954383895147, 2590.1064132874317, 2593.5201987632254, 2477.665515155467, 2499.762522560372, 2502.2118032819603, 2511.761382668662, 2514.0823599386276, 14109.0, 14515.782263266059, 14658.193799341032, 14667.77998330026, 14668.339924703414, 7656.349210317129, 7896.210378962115, 7916.604169791487, 7918.6040697965445, 7948.165442628557, 5385.5960802939935, 5422.295101636762, 5471.452854714496, 5544.390117087117, 5563.527100196488, 1943.1353047893692, 1952.687569988795, 1956.1001939108028, 1990.0746153038906, 2005.6690646043385, 24916.50166996668, 24949.875250623812, 25062.749372506223, 25078.87460562722, 25150.24549263525, 6393.744250230032, 6535.0850880876715, 6573.835654108686, 6726.596404215763, 6738.831529211809, 16572.0, 16576.419825306297, 16580.75128873068, 16596.419125330554, 16598.170091495376, 7596.81007974805, 7658.0, 7704.383649308042, 7726.150123486412, 7770.728024519115, 6874.587524748531, 6903.9989201565495, 6917.7232910684015, 6926.896096558556, 7139.39315158207, 4987.476314986874, 5108.2848401223855, 5118.360204974379, 5124.1032819257225, 5148.768305426265, 1343.1605246720799, 1393.2337214531967, 1493.133982290269, 1504.6238440389902, 1522.5660686704457, 853.1468531468531, 875.1205038935828, 890.9242714369351, 893.7631527645179, 909.622506659742, 7862.567558784322, 7924.20757924203, 7938.325242354349, 7971.40214483916, 8004.5197288161735, 681.2880539835884, 684.0389253099403, 686.9072675188786, 699.6466784274011, 787.959892941312, 4740.549647783455, 4758.191107511731, 4817.373741413594, 4939.135651261015, 5031.949680503221, 19602.313919012766, 20154.69767953482, 20670.79329206719, 20691.27580534674, 20714.482137946372, 20326.898365508372, 20775.68836467454, 21031.579368412546, 21068.15727370919, 21090.261840835497, 10634.255602107933, 10642.36145831268, 10723.89276107229, 10773.030427261585, 10790.730231744112, 7604.733834315773, 7719.652615632423, 7777.57223352721, 7837.0, 7865.174156713462, 17599.736003959693, 17988.19053142638, 18031.819681803016, 18185.363512277007, 18220.45338639843, 18167.727484088013, 18411.447656570323, 18649.440516784518, 18680.0, 18856.24575016984, 10657.626983055556, 10785.946070269913, 10788.0, 10789.892101079202, 10801.837972430263, 14265.144091354552, 14625.0, 14651.413943442143, 14655.633609159857, 14765.926170369079, 11888.227265227733, 11905.583304584297, 12003.939980300043, 12318.568850090203, 12544.87455125437, 11782.0, 11835.881641183822, 11839.58561450345, 12209.877901220874, 12218.45016974256, 9976.60093596248, 10144.79710405773, 10185.49072546355, 10250.38497690141, 10250.794984100126, 6543.280239173592, 6750.1562304711915, 6866.279040700854, 6994.61529615876, 7032.296770322927, 6964.756233531802, 6971.79084627462, 6986.860262794818, 7188.424926006011, 7267.30960558746], "value": 6486.161037647602, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.4277809130456, 502.51256281407024, 512.7769420302168, 526.2474661234434, 530.0988319856245, 6232.719527621257, 6328.936710632892, 6367.402417634039, 6384.521160912933, 6464.288928217892, 6197.721102550385, 6199.163112979746, 6264.96867515663, 6276.843078923029, 6386.776462823802, 4991.425986011619, 5018.774155163026, 5177.948220517794, 5236.554892834095, 5314.149736042234, 5064.974675126619, 5081.872953176182, 5100.719460429675, 5138.2806407103035, 5147.485251474841, 5017.0, 5047.167217409123, 5086.6947983120945, 5090.185570308751, 5106.668066575679, 5018.899622007576, 5043.369578802632, 5058.266551350033, 5074.238864170367, 5075.720835354054, 4511.954880451186, 4524.276115821453, 4548.522405147478, 4553.886152846172, 4569.109023740372, 6231.22109736283, 6295.401936815995, 6313.242410910674, 6316.936830631681, 6327.936720632781, 5691.943080569183, 5700.37295897451, 5769.653820770725, 5770.038187016393, 5798.130280457923, 6604.801855944329, 6619.139511863427, 6634.170728658918, 6652.168478940132, 6766.695498702521, 4927.581155601778, 4929.260610908356, 4942.036302920932, 4944.653874228806, 4974.701517908937, 6611.834704132435, 6842.692078856414, 6893.345132212432, 6973.232944376115, 7062.399696025844, 4977.601791856643, 5006.724630145377, 5043.394792624908, 5051.898962020739, 5125.948740512622, 5071.797128114834, 5100.464451232641, 5111.156659151273, 5113.718745469035, 5145.305383773143, 5683.60214784961, 5768.030088858011, 5772.7690892363835, 5823.369456552172, 5856.0, 6767.492438067066, 6787.694553745093, 6803.319668033256, 6812.965935170292, 6815.318468153145, 1776.2184638758904, 1779.2527138601818, 1795.973060404095, 1802.5133214032137, 1803.7564928734716, 1158.7392836611696, 1193.5379160528285, 1205.5418940802615, 1218.1788226601288, 1239.1449899569268, 5855.414458554111, 5881.911771323434, 5892.970535147382, 5975.878563110034, 5990.760369585255, 6846.931530684729, 6856.794296171123, 6858.417034552119, 6883.380495755306, 6886.48351373649, 5808.477237048602, 5889.94110058894, 5901.970490147608, 5940.811837632491, 6000.939990600038, 3600.7479476436397, 3610.801405922647, 3621.8913432597064, 3644.398674218777, 3664.908377290589, 6547.214334279917, 6564.4420224281475, 6662.836875889782, 6664.366885145903, 6705.798826035227, 6868.62222577753, 6890.414314783199, 6923.307669233036, 6948.166220053525, 7073.257307982691, 6070.939290607037, 6499.0, 6500.674966251669, 6545.738170473128, 6602.900956485657, 6757.560758550679, 6804.931950680628, 6829.863402732017, 6853.623050732258, 6915.861682766417, 6578.703958321791, 6729.764458243938, 6729.85592449275, 6883.242843287335, 6893.414059804973, 6555.508336874658, 6557.803265902031, 6652.900206497004, 6724.663766811737, 6735.932640673531, 5031.295618613471, 5048.72232027242, 5087.109755792795, 5106.157484015096, 5111.386633603991, 6537.1501704778075, 6717.596944183365, 6770.187577490733, 6949.548279361826, 6954.408875245661, 6637.568558043712, 6662.866742665023, 6673.666316684244, 6747.932520674731, 6777.695003724943, 6544.44372228366, 6581.276059633532, 6595.241547222131, 6612.206535215805, 6759.188897332351, 6524.054012168114, 6545.37818907203, 6604.735810567524, 6625.701843416961, 6700.296468870698, 6747.392734653905, 6801.761938332134, 6806.583662799635, 6810.557313774589, 6827.283135270725, 6555.3772391622715, 6564.212294524687, 6566.9343306566325, 6569.671516424064, 6610.3389661035435], "value": 5142.389370374042, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [864.2222000199815, 879.9340049496288, 896.6054935828236, 898.4384759525296, 898.7797989492567, 18014.459566213, 19095.14071866766, 19231.615367692655, 19242.807571924277, 21304.254351097716, 15892.602684932883, 16112.274947627355, 16154.515364539082, 17766.378176763817, 20591.27930522432, 918.8529835226349, 937.2501998401252, 941.2375975459865, 945.0691069296737, 947.5972711597578, 912.7900582865944, 947.3842002698233, 967.6177909725654, 969.0503306759344, 969.7721035556639, 992.2260636703347, 999.170688328689, 1001.2390583156816, 1005.0401866217744, 1005.9396436213814, 993.2848349188573, 994.0705440413201, 996.8205722969863, 999.1457304005116, 1002.3584905660347, 733.9302766237199, 734.2914087905144, 734.720806093681, 734.9669264883094, 735.0297607158553, 18058.36795712157, 18738.344157954543, 18804.905975470036, 18881.150348234227, 19114.522136946547, 21099.261525846676, 21361.145554177816, 21641.675374869388, 21652.89173554138, 21671.67492487614, 17184.914075429668, 17352.47942561725, 17564.209610567556, 17705.38031168903, 17766.289348426046, 3740.121071548184, 3758.699304055669, 3762.0, 3767.171222331083, 3768.189839184561, 130282.3485882574, 131724.34137829347, 135239.32380338132, 136739.0, 137856.0, 16556.75164872528, 16579.834201658072, 16739.665206695798, 17008.57478563046, 17074.231659575347, 29828.701712983027, 30387.696123038928, 30412.391752164833, 30573.541396879064, 30657.540136897966, 8668.8699669505, 8770.956145219234, 8785.736427907173, 8792.8681069784, 8803.955980220058, 4235.470566179228, 4331.566843315643, 4364.585364390377, 4364.956350436455, 4399.186150562092, 5295.099833028395, 5410.242566040757, 5419.376771671229, 5427.565794736412, 5438.048341540294, 1031.29356390871, 1051.363924825485, 1067.1089640150406, 1069.0, 1071.164491696478, 689.5345641691838, 697.4385619576188, 702.3082263969985, 710.8436144048301, 720.7621484909968, 12487.93756031214, 12591.496340146292, 12688.682782930317, 12711.237325760483, 12885.484580616858, 696.2689176364796, 702.7645738677498, 708.1643660480682, 719.704531842681, 723.269497807208, 5500.532454741312, 5828.388019258002, 5850.859082478876, 5890.823275301748, 5902.8819423611285, 108948.45525772321, 111154.0, 114665.0, 117359.0, 118020.40989795167, 97554.0, 98873.50563247282, 101720.49139754254, 103289.48355258176, 103835.0, 90405.54797225972, 93408.53295733478, 93713.53143234376, 93933.5303323479, 94079.52960235155, 12086.39568021613, 12547.560835370718, 12865.871341286842, 12933.935330323287, 13196.340182991004, 40065.0, 40104.598954010085, 40273.1945361097, 40496.97515124173, 41074.794626026676, 71636.64181679058, 73081.0, 73100.0, 74582.38961636317, 76814.61592692001, 18791.24835006639, 19324.61350773005, 19388.12753426071, 19500.707489387387, 19507.60984780268, 21313.573728525032, 21393.572128557655, 22359.105635774387, 22522.436939076655, 22654.660180096984, 18451.630967380308, 18587.907060465153, 18636.534086647942, 18731.0, 19716.01419928969, 19506.804931950497, 19854.205831767144, 19976.001199939656, 19991.800081999576, 20259.0, 19185.0, 19244.422667320003, 19257.22971081197, 19467.318643847964, 19706.90146549258, 14462.566123016326, 14551.781723274371, 14613.123212607277, 14636.121832690073, 14725.484608038667, 14232.0, 14236.572902812932, 14302.0, 14321.069130506483, 15003.924980375465], "value": 9307.348738526694, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1506.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 107.0, 99.0, 105.0, 106.0, 111.0, 114.0, 97.0, 106.0, 114.0], "value": 106.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 120.0, 149.0, 173.0, 118.0, 151.0, 160.0, 117.0, 171.0, 119.0], "value": 142.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 171.0, 163.0, 165.0, 170.0, 173.0, 166.0, 164.0, 162.0], "value": 165.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 77.0, 154.0, 75.0, 73.0, 74.0, 89.0, 95.0, 74.0], "value": 85.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 136.0, 79.0, 79.0, 78.0, 132.0, 78.0, 82.0, 129.0], "value": 95.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 121.0, 126.0, 121.0, 116.0, 114.0, 119.0, 114.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 147.0, 149.0, 149.0, 148.0, 147.0, 147.0, 149.0, 153.0], "value": 148.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 98.0, 94.0, 95.0, 93.0, 94.0, 94.0, 96.0, 94.0, 94.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 57.0, 53.0, 53.0, 58.0, 51.0, 56.0, 52.0, 54.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 63.0, 54.0, 56.0, 55.0, 55.0, 55.0, 55.0, 55.0, 56.0], "value": 56.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 81.0, 78.0, 73.0, 79.0, 90.0, 74.0, 74.0], "value": 77.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 122.0, 118.0, 119.0, 116.0, 118.0, 122.0, 129.0, 114.0, 153.0], "value": 123.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 179.0, 184.0, 170.0, 171.0, 182.0, 174.0, 172.0, 170.0, 169.0], "value": 174.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 66.0, 63.0, 63.0, 62.0, 65.0, 66.0, 64.0, 63.0], "value": 64.0, "unit": "score"}]}]} 18:33:58 INFO - Return code: 0 18:33:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:33:58 INFO - # TBPL SUCCESS # 18:33:58 INFO - Running post-action listener: _package_coverage_data 18:33:58 INFO - Running post-action listener: _resource_record_post_action 18:33:58 INFO - [mozharness: 2017-08-26 01:33:58.814058Z] Finished run-tests step (success) 18:33:58 INFO - Running post-run listener: _resource_record_post_run 18:33:58 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 438386688; Read time: 16; Write time: 292064 18:33:58 INFO - TinderboxPrint: CPU usage
13.1% 18:33:58 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 18:33:58 INFO - TinderboxPrint: I/O write bytes / time
438,386,688 / 292,064 18:33:58 INFO - TinderboxPrint: CPU idle
8,261.5 (86.8%) 18:33:58 INFO - TinderboxPrint: CPU user
1,200.2 (12.6%) 18:33:58 INFO - TinderboxPrint: Swap in / out
0 / 0 18:33:58 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 116027392; Read time: 0; Write time: 96196 18:33:58 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:33:58 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 321335296; Read time: 0; Write time: 195860 18:33:59 INFO - Running post-run listener: _upload_blobber_files 18:33:59 INFO - Blob upload gear active. 18:33:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:33:59 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:33:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:33:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:33:59 INFO - (blobuploader) - INFO - Open directory for files ... 18:33:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:33:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:33:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:34:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:34:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:34:00 INFO - (blobuploader) - INFO - Done attempting. 18:34:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:34:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:34:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:34:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:34:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:34:02 INFO - (blobuploader) - INFO - Done attempting. 18:34:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:34:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:34:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:34:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:34:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:34:02 INFO - (blobuploader) - INFO - Done attempting. 18:34:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:34:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:34:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:34:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:34:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:34:10 INFO - (blobuploader) - INFO - Done attempting. 18:34:10 INFO - (blobuploader) - INFO - Iteration through files over. 18:34:10 INFO - Return code: 0 18:34:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:34:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:34:10 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9e47df95c34106244b4a8b66b2fea3b2e7f4988a57235c955545d037afa7e2c29bdb6e293a60243a8179c8cb734dc18c3a8540ba1ab7f034e414fdd3b8b8b16", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2fc969d64701012c9fadbc1de4d923e2dced007af935279a722339a609d7081a5f3d20cb15759672a41293b7faa8a1fb8896731e60986acdf4e364c96ad349f2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96fad93d7eefa96c76c5bb7e257c64bfe1a99fa67331798c8d2c399ffc7740fad3c09b8342b001be8a58d67d879631443a0f0125b3c9ab926e2e309cb836ffff", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/318c75d8c15c91a34413f1e89f51e4ecdf527d697cc246b75fda21d41880a24bfb898908ecf2b5c77c0aaf768087c8b62d3dc1849345997c84a6f6371c315f41"} 18:34:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:34:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:34:10 INFO - Contents: 18:34:10 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9e47df95c34106244b4a8b66b2fea3b2e7f4988a57235c955545d037afa7e2c29bdb6e293a60243a8179c8cb734dc18c3a8540ba1ab7f034e414fdd3b8b8b16", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2fc969d64701012c9fadbc1de4d923e2dced007af935279a722339a609d7081a5f3d20cb15759672a41293b7faa8a1fb8896731e60986acdf4e364c96ad349f2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96fad93d7eefa96c76c5bb7e257c64bfe1a99fa67331798c8d2c399ffc7740fad3c09b8342b001be8a58d67d879631443a0f0125b3c9ab926e2e309cb836ffff", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/318c75d8c15c91a34413f1e89f51e4ecdf527d697cc246b75fda21d41880a24bfb898908ecf2b5c77c0aaf768087c8b62d3dc1849345997c84a6f6371c315f41"} 18:34:10 INFO - Running post-run listener: copy_logs_to_upload_dir 18:34:10 INFO - Copying logs to upload dir... 18:34:10 INFO - mkdir: /builds/slave/test/build/upload/logs 18:34:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.075730 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-25 18:34:10.098808) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-25 18:34:10.105126) ========= 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=7de08804244eb36560b44971000001c3-1503709990.413982-988804046 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/b9e47df95c34106244b4a8b66b2fea3b2e7f4988a57235c955545d037afa7e2c29bdb6e293a60243a8179c8cb734dc18c3a8540ba1ab7f034e414fdd3b8b8b16", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2fc969d64701012c9fadbc1de4d923e2dced007af935279a722339a609d7081a5f3d20cb15759672a41293b7faa8a1fb8896731e60986acdf4e364c96ad349f2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96fad93d7eefa96c76c5bb7e257c64bfe1a99fa67331798c8d2c399ffc7740fad3c09b8342b001be8a58d67d879631443a0f0125b3c9ab926e2e309cb836ffff", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/318c75d8c15c91a34413f1e89f51e4ecdf527d697cc246b75fda21d41880a24bfb898908ecf2b5c77c0aaf768087c8b62d3dc1849345997c84a6f6371c315f41"} build_url:https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011315 build_url: 'https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9e47df95c34106244b4a8b66b2fea3b2e7f4988a57235c955545d037afa7e2c29bdb6e293a60243a8179c8cb734dc18c3a8540ba1ab7f034e414fdd3b8b8b16", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2fc969d64701012c9fadbc1de4d923e2dced007af935279a722339a609d7081a5f3d20cb15759672a41293b7faa8a1fb8896731e60986acdf4e364c96ad349f2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96fad93d7eefa96c76c5bb7e257c64bfe1a99fa67331798c8d2c399ffc7740fad3c09b8342b001be8a58d67d879631443a0f0125b3c9ab926e2e309cb836ffff", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/318c75d8c15c91a34413f1e89f51e4ecdf527d697cc246b75fda21d41880a24bfb898908ecf2b5c77c0aaf768087c8b62d3dc1849345997c84a6f6371c315f41"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-25 18:34:10.131415) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 18:34:10.133200) ========= 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=7de08804244eb36560b44971000001c3-1503709990.413982-988804046 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004511 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 18:34:10.214460) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-25 18:34:10.216810) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-25 18:34:10.217152) ========= ========= Total master_lag: 0.22 =========