builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-049 starttime: 1504282397.26 results: success (0) revision: 34933f6390d52779ea498a6a5fd5f34d54734780 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 09:13:17.258555) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 09:13:17.259031) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 09:13:17.259357) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 09:13:17.275385) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:13:17.275721) ========= 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=db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-01 09:13:17-- 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% 97.6M=0s 2017-09-01 09:13:17 (97.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.099399 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:13:17.390137) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:13:17.390473) ========= 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=db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014855 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:13:17.440383) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:13:17.440723) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 34933f6390d52779ea498a6a5fd5f34d54734780 --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 34933f6390d52779ea498a6a5fd5f34d54734780 --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=db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-01 09:13:17,497 truncating revision to first 12 chars 2017-09-01 09:13:17,498 Setting DEBUG logging. 2017-09-01 09:13:17,498 attempt 1/10 2017-09-01 09:13:17,498 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/34933f6390d5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-01 09:13:18,005 unpacking tar archive at: mozilla-central-34933f6390d5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.888988 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:13:18.341012) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 09:13:18.341350) ========= script_repo_revision: 34933f6390d52779ea498a6a5fd5f34d54734780 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 09:13:18.341768) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 09:13:18.342200) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 09:13:18.353446) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-01 09:13:18.353839) ========= /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=db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:13:18 INFO - MultiFileLogger online at 20170901 09:13:18 in /builds/slave/test 09:13:18 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 09:13:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:13:18 INFO - {'append_to_log': False, 09:13:18 INFO - 'base_work_dir': '/builds/slave/test', 09:13:18 INFO - 'blob_upload_branch': 'Firefox', 09:13:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:13:18 INFO - 'branch': 'Firefox', 09:13:18 INFO - 'buildbot_json_path': 'buildprops.json', 09:13:18 INFO - 'code_coverage': False, 09:13:18 INFO - 'config_files': ('talos/linux_config.py',), 09:13:18 INFO - 'default_actions': ('clobber', 09:13:18 INFO - 'read-buildbot-config', 09:13:18 INFO - 'download-and-extract', 09:13:18 INFO - 'populate-webroot', 09:13:18 INFO - 'create-virtualenv', 09:13:18 INFO - 'install', 09:13:18 INFO - 'setup-mitmproxy', 09:13:18 INFO - 'run-tests'), 09:13:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:13:18 INFO - 'disable_ccov_upload': False, 09:13:18 INFO - 'disable_stylo': False, 09:13:18 INFO - 'download_minidump_stackwalk': True, 09:13:18 INFO - 'download_symbols': 'ondemand', 09:13:18 INFO - 'enable_stylo': False, 09:13:18 INFO - 'enable_webrender': False, 09:13:18 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 09:13:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:13:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:13:18 INFO - 'gecko_profile': False, 09:13:18 INFO - 'gecko_profile_interval': 0, 09:13:18 INFO - 'installer_path': 'installer.exe', 09:13:18 INFO - 'log_level': 'info', 09:13:18 INFO - 'log_name': 'talos', 09:13:18 INFO - 'log_to_console': True, 09:13:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:13:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:13:18 INFO - 'opt_config_files': (), 09:13:18 INFO - 'pip_index': False, 09:13:18 INFO - 'suite': 'dromaeojs-e10s', 09:13:18 INFO - 'system_bits': '32', 09:13:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:13:18 INFO - 'title': 'talos-linux64-ix-049', 09:13:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:13:18 INFO - 'use_talos_json': True, 09:13:18 INFO - 'verify': 'False', 09:13:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:13:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:13:18 INFO - 'work_dir': 'build'} 09:13:18 INFO - [mozharness: 2017-09-01 16:13:18.552022Z] Running clobber step. 09:13:18 INFO - Running pre-action listener: _resource_record_pre_action 09:13:18 INFO - Running main action method: clobber 09:13:18 INFO - rmtree: /builds/slave/test/build 09:13:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:13:19 INFO - Running post-action listener: _resource_record_post_action 09:13:19 INFO - [mozharness: 2017-09-01 16:13:19.572729Z] Finished clobber step (success) 09:13:19 INFO - [mozharness: 2017-09-01 16:13:19.572825Z] Running read-buildbot-config step. 09:13:19 INFO - Running pre-action listener: _resource_record_pre_action 09:13:19 INFO - Running main action method: read_buildbot_config 09:13:19 INFO - Using buildbot properties: 09:13:19 INFO - { 09:13:19 INFO - "project": "", 09:13:19 INFO - "product": "firefox", 09:13:19 INFO - "who": "archaeopteryx@coole-files.de", 09:13:19 INFO - "installer_path": "public/build/target.tar.bz2", 09:13:19 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 09:13:19 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 09:13:19 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 09:13:19 INFO - "stage_platform": "linux64-stylo", 09:13:19 INFO - "basedir": "/builds/slave/test", 09:13:19 INFO - "buildnumber": 291, 09:13:19 INFO - "platform": "ubuntu64_hw_stylo", 09:13:19 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:13:19 INFO - "slavebuilddir": "test", 09:13:19 INFO - "taskId": "bN2KqMbrSbCK0HfRJPDr-A", 09:13:19 INFO - "branch": "mozilla-central", 09:13:19 INFO - "script_repo_revision": "production", 09:13:19 INFO - "revision": "34933f6390d52779ea498a6a5fd5f34d54734780", 09:13:19 INFO - "slavename": "talos-linux64-ix-049", 09:13:19 INFO - "repo_path": "mozilla-central" 09:13:19 INFO - } 09:13:19 INFO - Finding installer, test and symbols from parent task. 09:13:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bN2KqMbrSbCK0HfRJPDr-A'}, attempt #1 09:13:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bN2KqMbrSbCK0HfRJPDr-A'}, attempt #1 09:13:20 INFO - Task dependencies: HW9QGOoZRpaQ0PfZ0HF4ew 09:13:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew'}, attempt #1 09:13:21 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.tar.bz2 09:13:21 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.test_packages.json 09:13:21 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.crashreporter-symbols.zip 09:13:21 INFO - Running post-action listener: _resource_record_post_action 09:13:21 INFO - [mozharness: 2017-09-01 16:13:21.028435Z] Finished read-buildbot-config step (success) 09:13:21 INFO - [mozharness: 2017-09-01 16:13:21.028648Z] Running download-and-extract step. 09:13:21 INFO - Running pre-action listener: _resource_record_pre_action 09:13:21 INFO - Running main action method: download_and_extract 09:13:21 INFO - mkdir: /builds/slave/test/build/tests 09:13:21 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')]} 09:13:21 INFO - trying https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.test_packages.json 09:13:21 INFO - Downloading https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:13:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:13:21 INFO - Downloaded 989 bytes. 09:13:21 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:13:21 INFO - Using the following test package requirements: 09:13:21 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:13:21 INFO - u'common': [u'target.common.tests.zip'], 09:13:21 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:13:21 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:13:21 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:13:21 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:13:21 INFO - u'mozbase': [u'target.common.tests.zip'], 09:13:21 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:13:21 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:13:21 INFO - u'web-platform': [u'target.common.tests.zip', 09:13:21 INFO - u'target.web-platform.tests.tar.gz'], 09:13:21 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:13:21 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:13:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.common.tests.zip 09:13:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:13:21 INFO - Fetch https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.common.tests.zip into memory 09:13:22 INFO - Content-Length response header: 54957582 09:13:22 INFO - Bytes received: 54957582 09:13:33 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:13:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.common.tests.zip 09:13:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:13:33 INFO - Fetch https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.common.tests.zip into memory 09:13:34 INFO - Content-Length response header: 54957582 09:13:34 INFO - Bytes received: 54957582 09:13:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.talos.tests.zip 09:13:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:13:46 INFO - Fetch https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.talos.tests.zip into memory 09:13:47 INFO - Content-Length response header: 14496650 09:13:47 INFO - Bytes received: 14496650 09: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')]} 09:13:47 INFO - trying https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.tar.bz2 09:13:47 INFO - Downloading https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:13:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:13:48 INFO - Downloaded 62495046 bytes. 09:13:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.tar.bz2 09:13:48 INFO - mkdir: /builds/slave/test/properties 09:13:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:13:48 INFO - Writing to file /builds/slave/test/properties/build_url 09:13:48 INFO - Contents: 09:13:48 INFO - build_url:https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.tar.bz2 09:13:48 INFO - Running post-action listener: _resource_record_post_action 09:13:48 INFO - Running post-action listener: find_tests_for_verification 09:13:48 INFO - Running post-action listener: set_extra_try_arguments 09:13:48 INFO - [mozharness: 2017-09-01 16:13:48.684231Z] Finished download-and-extract step (success) 09:13:48 INFO - [mozharness: 2017-09-01 16:13:48.684326Z] Running populate-webroot step. 09:13:48 INFO - Running pre-action listener: _resource_record_pre_action 09:13:48 INFO - Running main action method: populate_webroot 09:13:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:13:48 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:13:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:13:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:13:48 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:13:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:13:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:13:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:13:48 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 09:13:48 INFO - 'talos_options': ['--enable-stylo'], 09:13:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:13:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:13:48 INFO - 'tests': ['damp', 'tps']}, 09:13:48 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 09:13:48 INFO - 'talos_options': ['--enable-stylo'], 09:13:48 INFO - 'tests': ['damp', 'tps']}, 09:13:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:13:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:13:48 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:13:48 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:13:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:13:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:13:48 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 09:13:48 INFO - 'talos_options': ['--enable-stylo'], 09:13:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:13:48 INFO - 'other-e10s': {'tests': ['a11yr', 09:13:48 INFO - 'ts_paint', 09:13:48 INFO - 'tpaint', 09:13:48 INFO - 'sessionrestore', 09:13:48 INFO - 'sessionrestore_many_windows', 09:13:48 INFO - 'sessionrestore_no_auto_restore', 09:13:48 INFO - 'tabpaint']}, 09:13:48 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:13:48 INFO - 'tests': ['a11yr', 09:13:48 INFO - 'ts_paint', 09:13:48 INFO - 'tpaint', 09:13:48 INFO - 'sessionrestore', 09:13:48 INFO - 'sessionrestore_many_windows', 09:13:48 INFO - 'sessionrestore_no_auto_restore', 09:13:48 INFO - 'tabpaint']}, 09:13:48 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 09:13:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 09:13:48 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:13:48 INFO - 'tests': ['bloom_basic_singleton']}, 09:13:48 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:13:48 INFO - 'tests': ['bloom_basic']}, 09:13:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:13:48 INFO - 'tsvgr_opacity', 09:13:48 INFO - 'tart', 09:13:48 INFO - 'tscrollx', 09:13:48 INFO - 'tsvg_static']}, 09:13:48 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:13:48 INFO - 'tests': ['tsvgx', 09:13:48 INFO - 'tsvgr_opacity', 09:13:48 INFO - 'tart', 09:13:48 INFO - 'tscrollx', 09:13:48 INFO - 'tsvg_static']}, 09:13:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:13:48 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 09:13:48 INFO - 'talos_options': ['--enable-stylo'], 09:13:48 INFO - 'tests': ['tp5o']}, 09:13:48 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:13:48 INFO - 'talos_options': ['--mitmproxy', 09:13:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:13:48 INFO - '--firstNonBlankPaint'], 09:13:48 INFO - 'tests': ['tp6_google', 09:13:48 INFO - 'tp6_youtube', 09:13:48 INFO - 'tp6_amazon', 09:13:48 INFO - 'tp6_facebook']}, 09:13:48 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:13:48 INFO - 'talos_options': ['--enable-stylo', 09:13:48 INFO - '--mitmproxy', 09:13:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:13:48 INFO - '--firstNonBlankPaint'], 09:13:48 INFO - 'tests': ['tp6_google', 09:13:48 INFO - 'tp6_youtube', 09:13:48 INFO - 'tp6_amazon', 09:13:48 INFO - 'tp6_facebook']}, 09:13:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:13:48 INFO - 'talos_options': ['--enable-stylo', 09:13:48 INFO - '--stylo-threads=1', 09:13:48 INFO - '--mitmproxy', 09:13:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:13:48 INFO - '--firstNonBlankPaint'], 09:13:48 INFO - 'tests': ['tp6_google', 09:13:48 INFO - 'tp6_youtube', 09:13:48 INFO - 'tp6_amazon', 09:13:48 INFO - 'tp6_facebook']}, 09:13:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:13:48 INFO - 'talos_options': ['--xperf_path', 09:13:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:13:48 INFO - 'tests': ['tp5n']}, 09:13:48 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 09:13:48 INFO - 'talos_options': ['--enable-stylo', 09:13:48 INFO - '--xperf_path', 09:13:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:13:48 INFO - 'tests': ['tp5n']}}} 09:13:48 INFO - Running post-action listener: _resource_record_post_action 09:13:48 INFO - [mozharness: 2017-09-01 16:13:48.698589Z] Finished populate-webroot step (success) 09:13:48 INFO - [mozharness: 2017-09-01 16:13:48.698677Z] Running create-virtualenv step. 09:13:48 INFO - Running pre-action listener: _resource_record_pre_action 09:13:48 INFO - Running main action method: create_virtualenv 09:13:48 INFO - Creating virtualenv /builds/slave/test/build/venv 09:13:48 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:13:48 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 09:13:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:13:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:13:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:13:49 INFO - Installing distribute.............................................................................................................................................................................................done. 09:13:49 INFO - Installing pip...............done. 09:13:49 INFO - Return code: 0 09:13:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:13:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:13:49 INFO - Reading from file tmpfile_stdout 09:13:49 INFO - Output received: 09:13:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 09:13:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:13:49 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')]} 09:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:49 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')]} 09:13:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:49 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 0x10b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x127ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1421a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1425090>, '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 0x1425d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x141ff90>, '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': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', '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 09:13:49 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 09:13:49 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 09:13:49 INFO - Using env: {'DISPLAY': ':0', 09:13:49 INFO - 'HOME': '/home/cltbld', 09:13:49 INFO - 'LANG': 'en_US.UTF-8', 09:13:49 INFO - 'LANGUAGE': 'en_US:en', 09:13:49 INFO - 'LOGNAME': 'cltbld', 09:13:49 INFO - 'MAIL': '/var/mail/cltbld', 09:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:49 INFO - 'MOZ_NO_REMOTE': '1', 09:13:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:49 INFO - 'NO_EM_RESTART': '1', 09:13:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:49 INFO - 'PWD': '/builds/slave/test', 09:13:49 INFO - 'SHELL': '/bin/bash', 09:13:49 INFO - 'SHLVL': '1', 09:13:49 INFO - 'TERM': 'linux', 09:13:49 INFO - 'TMOUT': '86400', 09:13:49 INFO - 'USER': 'cltbld', 09:13:49 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', 09:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:49 INFO - '_': '/tools/buildbot/bin/python'} 09:13:50 INFO - Ignoring indexes: http://pypi.python.org/simple/ 09:13:50 INFO - Downloading/unpacking pip>=1.5 09:13:50 INFO - Running setup.py egg_info for package pip 09:13:50 INFO - warning: no files found matching 'pip/cacert.pem' 09:13:50 INFO - warning: no files found matching '*.html' under directory 'docs' 09:13:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 09:13:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 09:13:50 INFO - Installing collected packages: pip 09:13:50 INFO - Found existing installation: pip 1.1 09:13:50 INFO - Uninstalling pip: 09:13:50 INFO - Successfully uninstalled pip 09:13:50 INFO - Running setup.py install for pip 09:13:50 INFO - warning: no files found matching 'pip/cacert.pem' 09:13:50 INFO - warning: no files found matching '*.html' under directory 'docs' 09:13:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 09:13:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 09:13:50 INFO - Installing pip script to /builds/slave/test/build/venv/bin 09:13:50 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 09:13:50 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 09:13:50 INFO - Successfully installed pip 09:13:50 INFO - Cleaning up... 09:13:50 INFO - Return code: 0 09:13:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09: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')]} 09:13:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09: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')]} 09:13:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09: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', '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 0x10b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x127ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1421a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1425090>, '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 0x1425d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x141ff90>, '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': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', '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 09: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', 'psutil>=3.1.1'] in /builds/slave/test/build 09: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 psutil>=3.1.1 09:13:50 INFO - Using env: {'DISPLAY': ':0', 09:13:50 INFO - 'HOME': '/home/cltbld', 09:13:50 INFO - 'LANG': 'en_US.UTF-8', 09:13:50 INFO - 'LANGUAGE': 'en_US:en', 09:13:50 INFO - 'LOGNAME': 'cltbld', 09:13:50 INFO - 'MAIL': '/var/mail/cltbld', 09:13:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:50 INFO - 'MOZ_NO_REMOTE': '1', 09:13:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:50 INFO - 'NO_EM_RESTART': '1', 09:13:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:50 INFO - 'PWD': '/builds/slave/test', 09:13:50 INFO - 'SHELL': '/bin/bash', 09:13:50 INFO - 'SHLVL': '1', 09:13:50 INFO - 'TERM': 'linux', 09:13:50 INFO - 'TMOUT': '86400', 09:13:50 INFO - 'USER': 'cltbld', 09:13:50 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', 09:13:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:50 INFO - '_': '/tools/buildbot/bin/python'} 09:13:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:51 INFO - Downloading/unpacking psutil>=3.1.1 09:13:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:13:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:13:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:13:52 INFO - Installing collected packages: psutil 09:13:52 INFO - Running setup.py install for psutil 09:13:52 INFO - building 'psutil._psutil_linux' extension 09:13:52 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 09:13:52 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 09:13:52 INFO - building 'psutil._psutil_posix' extension 09:13:52 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 09:13:52 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 09:13:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:13:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:13:52 INFO - Successfully installed psutil 09:13:52 INFO - Cleaning up... 09:13:52 INFO - Return code: 0 09:13:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09: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')]} 09:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09: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')]} 09:13:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x127ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1421a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1425090>, '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 0x1425d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x141ff90>, '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': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', '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 09: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09: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 mozsystemmonitor==0.3 09:13:52 INFO - Using env: {'DISPLAY': ':0', 09:13:52 INFO - 'HOME': '/home/cltbld', 09:13:52 INFO - 'LANG': 'en_US.UTF-8', 09:13:52 INFO - 'LANGUAGE': 'en_US:en', 09:13:52 INFO - 'LOGNAME': 'cltbld', 09:13:52 INFO - 'MAIL': '/var/mail/cltbld', 09:13:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:52 INFO - 'MOZ_NO_REMOTE': '1', 09:13:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:52 INFO - 'NO_EM_RESTART': '1', 09:13:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:52 INFO - 'PWD': '/builds/slave/test', 09:13:52 INFO - 'SHELL': '/bin/bash', 09:13:52 INFO - 'SHLVL': '1', 09:13:52 INFO - 'TERM': 'linux', 09:13:52 INFO - 'TMOUT': '86400', 09:13:52 INFO - 'USER': 'cltbld', 09:13:52 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', 09:13:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:52 INFO - '_': '/tools/buildbot/bin/python'} 09:13:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 09: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 09: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 09: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 09: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 09:13:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:13:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:13:54 INFO - Installing collected packages: mozsystemmonitor 09:13:54 INFO - Running setup.py install for mozsystemmonitor 09:13:54 INFO - Successfully installed mozsystemmonitor 09:13:54 INFO - Cleaning up... 09:13:54 INFO - Return code: 0 09:13:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:13:54 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')]} 09:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:54 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')]} 09:13:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:54 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 0x10b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x127ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1421a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1425090>, '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 0x1425d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x141ff90>, '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': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', '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 09:13:54 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 09:13:54 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 09:13:54 INFO - Using env: {'DISPLAY': ':0', 09:13:54 INFO - 'HOME': '/home/cltbld', 09:13:54 INFO - 'LANG': 'en_US.UTF-8', 09:13:54 INFO - 'LANGUAGE': 'en_US:en', 09:13:54 INFO - 'LOGNAME': 'cltbld', 09:13:54 INFO - 'MAIL': '/var/mail/cltbld', 09:13:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:54 INFO - 'MOZ_NO_REMOTE': '1', 09:13:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:54 INFO - 'NO_EM_RESTART': '1', 09:13:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:54 INFO - 'PWD': '/builds/slave/test', 09:13:54 INFO - 'SHELL': '/bin/bash', 09:13:54 INFO - 'SHLVL': '1', 09:13:54 INFO - 'TERM': 'linux', 09:13:54 INFO - 'TMOUT': '86400', 09:13:54 INFO - 'USER': 'cltbld', 09:13:54 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', 09:13:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:54 INFO - '_': '/tools/buildbot/bin/python'} 09:13:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:54 INFO - Downloading/unpacking jsonschema==2.5.1 09: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 09: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 09: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 09: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 09:13:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:13:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09: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 09: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 09: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 09: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 09:13:55 INFO - Downloading functools32-3.2.3-2.tar.gz 09:13:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:13:55 INFO - warning: no files found matching '*.txt' 09:13:55 INFO - no previously-included directories found matching 'build' 09:13:55 INFO - no previously-included directories found matching 'dist' 09:13:55 INFO - no previously-included directories found matching '.git*' 09:13:55 INFO - Installing collected packages: jsonschema, functools32 09:13:55 INFO - Running setup.py install for functools32 09:13:55 INFO - warning: no files found matching '*.txt' 09:13:55 INFO - no previously-included directories found matching 'build' 09:13:55 INFO - no previously-included directories found matching 'dist' 09:13:55 INFO - no previously-included directories found matching '.git*' 09:13:55 INFO - Successfully installed jsonschema functools32 09:13:55 INFO - Cleaning up... 09:13:56 INFO - Return code: 0 09:13:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09: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')]} 09:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09: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')]} 09:13:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:56 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 0x10b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x127ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1421a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1425090>, '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 0x1425d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x141ff90>, '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': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', '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 09:13:56 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 09:13:56 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 09:13:56 INFO - Using env: {'DISPLAY': ':0', 09:13:56 INFO - 'HOME': '/home/cltbld', 09:13:56 INFO - 'LANG': 'en_US.UTF-8', 09:13:56 INFO - 'LANGUAGE': 'en_US:en', 09:13:56 INFO - 'LOGNAME': 'cltbld', 09:13:56 INFO - 'MAIL': '/var/mail/cltbld', 09:13:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:56 INFO - 'MOZ_NO_REMOTE': '1', 09:13:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:56 INFO - 'NO_EM_RESTART': '1', 09:13:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:56 INFO - 'PWD': '/builds/slave/test', 09:13:56 INFO - 'SHELL': '/bin/bash', 09:13:56 INFO - 'SHLVL': '1', 09:13:56 INFO - 'TERM': 'linux', 09:13:56 INFO - 'TMOUT': '86400', 09:13:56 INFO - 'USER': 'cltbld', 09:13:56 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', 09:13:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:56 INFO - '_': '/tools/buildbot/bin/python'} 09:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:13:56 INFO - Cleaning up... 09:13:56 INFO - Return code: 0 09:13:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09: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')]} 09:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09: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')]} 09:13:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:56 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 0x10b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x127ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1421a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1425090>, '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 0x1425d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x141ff90>, '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': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', '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 09:13:56 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 09:13:56 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 09:13:56 INFO - Using env: {'DISPLAY': ':0', 09:13:56 INFO - 'HOME': '/home/cltbld', 09:13:56 INFO - 'LANG': 'en_US.UTF-8', 09:13:56 INFO - 'LANGUAGE': 'en_US:en', 09:13:56 INFO - 'LOGNAME': 'cltbld', 09:13:56 INFO - 'MAIL': '/var/mail/cltbld', 09:13:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:56 INFO - 'MOZ_NO_REMOTE': '1', 09:13:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:56 INFO - 'NO_EM_RESTART': '1', 09:13:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:56 INFO - 'PWD': '/builds/slave/test', 09:13:56 INFO - 'SHELL': '/bin/bash', 09:13:56 INFO - 'SHLVL': '1', 09:13:56 INFO - 'TERM': 'linux', 09:13:56 INFO - 'TMOUT': '86400', 09:13:56 INFO - 'USER': 'cltbld', 09:13:56 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', 09:13:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:56 INFO - '_': '/tools/buildbot/bin/python'} 09:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:56 INFO - Downloading/unpacking blobuploader==1.2.4 09:13:56 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 09:13:56 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 09:13:56 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 09:13:56 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 09:13:57 INFO - Downloading blobuploader-1.2.4.tar.gz 09:13:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:13:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:13:57 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 09:13:57 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 09:13:57 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 09:13:57 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 09:13:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:13:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:13:57 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 09:13:57 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 09:13:57 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 09:13:57 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 09:13:58 INFO - Downloading docopt-0.6.1.tar.gz 09:13:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:13:58 INFO - Installing collected packages: blobuploader, requests, docopt 09:13:58 INFO - Running setup.py install for blobuploader 09:13:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:13:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:13:58 INFO - Running setup.py install for requests 09:13:58 INFO - Running setup.py install for docopt 09:13:58 INFO - Successfully installed blobuploader requests docopt 09:13:58 INFO - Cleaning up... 09:13:58 INFO - Return code: 0 09:13:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:58 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 0x10b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x127ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1421a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1425090>, '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 0x1425d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x141ff90>, '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': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', '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 09:13:58 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 09:13:58 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 09:13:58 INFO - Using env: {'DISPLAY': ':0', 09:13:58 INFO - 'HOME': '/home/cltbld', 09:13:58 INFO - 'LANG': 'en_US.UTF-8', 09:13:58 INFO - 'LANGUAGE': 'en_US:en', 09:13:58 INFO - 'LOGNAME': 'cltbld', 09:13:58 INFO - 'MAIL': '/var/mail/cltbld', 09:13:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:58 INFO - 'MOZ_NO_REMOTE': '1', 09:13:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:58 INFO - 'NO_EM_RESTART': '1', 09:13:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:58 INFO - 'PWD': '/builds/slave/test', 09:13:58 INFO - 'SHELL': '/bin/bash', 09:13:58 INFO - 'SHLVL': '1', 09:13:58 INFO - 'TERM': 'linux', 09:13:58 INFO - 'TMOUT': '86400', 09:13:58 INFO - 'USER': 'cltbld', 09:13:58 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', 09:13:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:58 INFO - '_': '/tools/buildbot/bin/python'} 09:13:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:13:58 INFO - Running setup.py (path:/tmp/pip-CbjHPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:13:58 INFO - Running setup.py (path:/tmp/pip-yeMYQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:13:59 INFO - Running setup.py (path:/tmp/pip-XQoALb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:13:59 INFO - Running setup.py (path:/tmp/pip-XUczCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:13:59 INFO - Running setup.py (path:/tmp/pip-vXy12p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:13:59 INFO - Running setup.py (path:/tmp/pip-Ob5CX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:13:59 INFO - Running setup.py (path:/tmp/pip-rMOkRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:13:59 INFO - Running setup.py (path:/tmp/pip-8fHQVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:13:59 INFO - Running setup.py (path:/tmp/pip-eKIA_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:13:59 INFO - Running setup.py (path:/tmp/pip-NgjsIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:13:59 INFO - Running setup.py (path:/tmp/pip-1YR4wN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:13:59 INFO - Running setup.py (path:/tmp/pip-GMLlEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:13:59 INFO - Running setup.py (path:/tmp/pip-JpLiFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:13:59 INFO - Running setup.py (path:/tmp/pip-a1uSiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:59 INFO - Running setup.py (path:/tmp/pip-7Y2noy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:13:59 INFO - Running setup.py (path:/tmp/pip-bnIHpy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:14:00 INFO - Running setup.py (path:/tmp/pip-vFeUI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:14:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:14:00 INFO - Running setup.py install for manifestparser 09:14:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:14:00 INFO - Running setup.py install for mozcrash 09:14:00 INFO - Running setup.py install for mozdebug 09:14:00 INFO - Running setup.py install for mozdevice 09:14:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:14:00 INFO - Running setup.py install for mozfile 09:14:00 INFO - Running setup.py install for mozhttpd 09:14:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:14:00 INFO - Running setup.py install for mozinfo 09:14:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:14:00 INFO - Running setup.py install for mozInstall 09:14:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:14:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:14:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:14:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:14:00 INFO - Running setup.py install for mozleak 09:14:00 INFO - Running setup.py install for mozlog 09:14:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:14:01 INFO - Running setup.py install for moznetwork 09:14:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:14:01 INFO - Running setup.py install for mozprocess 09:14:01 INFO - Running setup.py install for mozprofile 09:14:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:14:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:14:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:14:01 INFO - Running setup.py install for mozrunner 09:14:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:14:01 INFO - Running setup.py install for mozscreenshot 09:14:01 INFO - Running setup.py install for moztest 09:14:01 INFO - Running setup.py install for mozversion 09:14:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:14:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:14:01 INFO - Cleaning up... 09:14:01 INFO - Return code: 0 09:14:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:01 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 0x10b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x127ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1421a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1425090>, '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 0x1425d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x141ff90>, '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': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', '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 09:14:01 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 09:14:01 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 09:14:01 INFO - Using env: {'DISPLAY': ':0', 09:14:01 INFO - 'HOME': '/home/cltbld', 09:14:01 INFO - 'LANG': 'en_US.UTF-8', 09:14:01 INFO - 'LANGUAGE': 'en_US:en', 09:14:01 INFO - 'LOGNAME': 'cltbld', 09:14:01 INFO - 'MAIL': '/var/mail/cltbld', 09:14:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:01 INFO - 'MOZ_NO_REMOTE': '1', 09:14:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:01 INFO - 'NO_EM_RESTART': '1', 09:14:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:01 INFO - 'PWD': '/builds/slave/test', 09:14:01 INFO - 'SHELL': '/bin/bash', 09:14:01 INFO - 'SHLVL': '1', 09:14:01 INFO - 'TERM': 'linux', 09:14:01 INFO - 'TMOUT': '86400', 09:14:01 INFO - 'USER': 'cltbld', 09:14:01 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', 09:14:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:01 INFO - '_': '/tools/buildbot/bin/python'} 09:14:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:14:02 INFO - Running setup.py (path:/tmp/pip-IUKIln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:14:02 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)) 09:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:14:02 INFO - Running setup.py (path:/tmp/pip-rLEabX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:14:02 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)) 09:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:14:02 INFO - Running setup.py (path:/tmp/pip-qcmTo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:14:02 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)) 09:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:14:02 INFO - Running setup.py (path:/tmp/pip-DBFSB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:14:02 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)) 09:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:14:02 INFO - Running setup.py (path:/tmp/pip-F8UQfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:14:02 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)) 09:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:14:02 INFO - Running setup.py (path:/tmp/pip-qoRakO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:14:02 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)) 09:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:14:02 INFO - Running setup.py (path:/tmp/pip-1mjke0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:14:02 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)) 09:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:14:02 INFO - Running setup.py (path:/tmp/pip-DHOCoF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:14:02 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)) 09:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:14:02 INFO - Running setup.py (path:/tmp/pip-s5MNJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:14:02 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)) 09:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:14:02 INFO - Running setup.py (path:/tmp/pip-i6wYcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:14:02 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)) 09:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:14:02 INFO - Running setup.py (path:/tmp/pip-Pe40AT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:14:02 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)) 09:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:14:02 INFO - Running setup.py (path:/tmp/pip-PZmYOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:14:02 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)) 09:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:14:02 INFO - Running setup.py (path:/tmp/pip-aBDtcr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:14:03 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)) 09:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:14:03 INFO - Running setup.py (path:/tmp/pip-qLvBrk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:14:03 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)) 09:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:14:03 INFO - Running setup.py (path:/tmp/pip-GnUG_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:14:03 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)) 09:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:14:03 INFO - Running setup.py (path:/tmp/pip-8P0tD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:14:03 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)) 09:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:14:03 INFO - Running setup.py (path:/tmp/pip-s209ga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:14:03 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)) 09:14:03 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)) 09:14:03 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)) 09:14:03 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)) 09:14:03 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)) 09:14:03 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)) 09:14:03 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)) 09:14:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:14:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:04 INFO - Downloading blessings-1.6.tar.gz 09:14:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:14:04 INFO - Installing collected packages: blessings 09:14:04 INFO - Running setup.py install for blessings 09:14:04 INFO - Successfully installed blessings 09:14:04 INFO - Cleaning up... 09:14:04 INFO - Return code: 0 09:14:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:14:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:14:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:14:04 INFO - Reading from file tmpfile_stdout 09:14:04 INFO - Current package versions: 09:14:04 INFO - argparse == 1.2.1 09:14:04 INFO - blessings == 1.6 09:14:04 INFO - blobuploader == 1.2.4 09:14:04 INFO - docopt == 0.6.1 09:14:04 INFO - functools32 == 3.2.3-2 09:14:04 INFO - jsonschema == 2.5.1 09:14:04 INFO - manifestparser == 1.1 09:14:04 INFO - mozInstall == 1.13 09:14:04 INFO - mozcrash == 1.0 09:14:04 INFO - mozdebug == 0.1 09:14:04 INFO - mozdevice == 0.51 09:14:04 INFO - mozfile == 1.2 09:14:04 INFO - mozhttpd == 0.7 09:14:04 INFO - mozinfo == 0.10 09:14:04 INFO - mozleak == 0.1 09:14:04 INFO - mozlog == 3.5 09:14:04 INFO - moznetwork == 0.27 09:14:04 INFO - mozprocess == 0.25 09:14:04 INFO - mozprofile == 0.28 09:14:04 INFO - mozrunner == 6.13 09:14:04 INFO - mozscreenshot == 0.1 09:14:04 INFO - mozsystemmonitor == 0.3 09:14:04 INFO - moztest == 0.8 09:14:04 INFO - mozversion == 1.4 09:14:04 INFO - psutil == 3.1.1 09:14:04 INFO - requests == 1.2.3 09:14:04 INFO - wsgiref == 0.1.2 09:14:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:14:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:04 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 0x10b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x127ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1421a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1425090>, '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 0x1425d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x141ff90>, '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': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', '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 09:14:04 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 09:14:04 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 09:14:04 INFO - Using env: {'DISPLAY': ':0', 09:14:04 INFO - 'HOME': '/home/cltbld', 09:14:04 INFO - 'LANG': 'en_US.UTF-8', 09:14:04 INFO - 'LANGUAGE': 'en_US:en', 09:14:04 INFO - 'LOGNAME': 'cltbld', 09:14:04 INFO - 'MAIL': '/var/mail/cltbld', 09:14:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:04 INFO - 'MOZ_NO_REMOTE': '1', 09:14:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:04 INFO - 'NO_EM_RESTART': '1', 09:14:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:04 INFO - 'PWD': '/builds/slave/test', 09:14:04 INFO - 'SHELL': '/bin/bash', 09:14:04 INFO - 'SHLVL': '1', 09:14:04 INFO - 'TERM': 'linux', 09:14:04 INFO - 'TMOUT': '86400', 09:14:04 INFO - 'USER': 'cltbld', 09:14:04 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', 09:14:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:04 INFO - '_': '/tools/buildbot/bin/python'} 09:14:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:05 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)) 09:14:05 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)) 09:14:05 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)) 09:14:05 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)) 09:14:05 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)) 09:14:05 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)) 09:14:05 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)) 09:14:05 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)) 09:14:05 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)) 09:14:05 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 09:14:06 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)) 09:14:06 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)) 09:14:06 INFO - Installing collected packages: simplejson 09:14:06 INFO - Running setup.py install for simplejson 09:14:06 INFO - building 'simplejson._speedups' extension 09:14:06 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 09:14:07 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 09:14:07 INFO - Successfully installed simplejson 09:14:07 INFO - Cleaning up... 09:14:07 INFO - Return code: 0 09:14:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:14:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:07 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 0x10b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x127ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1421a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1425090>, '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 0x1425d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x141ff90>, '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': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', '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 09:14:07 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 09:14:07 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 09:14:07 INFO - Using env: {'DISPLAY': ':0', 09:14:07 INFO - 'HOME': '/home/cltbld', 09:14:07 INFO - 'LANG': 'en_US.UTF-8', 09:14:07 INFO - 'LANGUAGE': 'en_US:en', 09:14:07 INFO - 'LOGNAME': 'cltbld', 09:14:07 INFO - 'MAIL': '/var/mail/cltbld', 09:14:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:07 INFO - 'MOZ_NO_REMOTE': '1', 09:14:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:07 INFO - 'NO_EM_RESTART': '1', 09:14:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:07 INFO - 'PWD': '/builds/slave/test', 09:14:07 INFO - 'SHELL': '/bin/bash', 09:14:07 INFO - 'SHLVL': '1', 09:14:07 INFO - 'TERM': 'linux', 09:14:07 INFO - 'TMOUT': '86400', 09:14:07 INFO - 'USER': 'cltbld', 09:14:07 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', 09:14:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:07 INFO - '_': '/tools/buildbot/bin/python'} 09:14:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:14:07 INFO - Cleaning up... 09:14:07 INFO - Return code: 0 09:14:07 INFO - Running post-action listener: _resource_record_post_action 09:14:07 INFO - Running post-action listener: _start_resource_monitoring 09:14:07 INFO - Starting resource monitoring. 09:14:07 INFO - [mozharness: 2017-09-01 16:14:07.619036Z] Finished create-virtualenv step (success) 09:14:07 INFO - [mozharness: 2017-09-01 16:14:07.619554Z] Running install step. 09:14:07 INFO - Running pre-action listener: _resource_record_pre_action 09:14:07 INFO - Running main action method: install 09:14:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:14:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:14:07 INFO - Reading from file tmpfile_stdout 09:14:07 INFO - Detecting whether we're running mozinstall >=1.0... 09:14:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:14:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:14:07 INFO - Reading from file tmpfile_stdout 09:14:07 INFO - Output received: 09:14:07 INFO - Usage: mozinstall [options] installer 09:14:07 INFO - Options: 09:14:07 INFO - -h, --help show this help message and exit 09:14:07 INFO - -d DEST, --destination=DEST 09:14:07 INFO - Directory to install application into. [default: 09:14:07 INFO - "/builds/slave/test"] 09:14:07 INFO - --app=APP Application being installed. [default: firefox] 09:14:07 INFO - mkdir: /builds/slave/test/build/application 09:14:07 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'] 09:14:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:14:24 INFO - Reading from file tmpfile_stdout 09:14:24 INFO - Output received: 09:14:24 INFO - /builds/slave/test/build/application/firefox/firefox 09:14:24 INFO - Running post-action listener: _resource_record_post_action 09:14:24 INFO - [mozharness: 2017-09-01 16:14:24.474255Z] Finished install step (success) 09:14:24 INFO - [mozharness: 2017-09-01 16:14:24.474510Z] Running setup-mitmproxy step. 09:14:24 INFO - Running pre-action listener: _resource_record_pre_action 09:14:24 INFO - Running main action method: setup_mitmproxy 09:14:24 INFO - Skipping: mitmproxy is not required 09:14:24 INFO - Running post-action listener: _resource_record_post_action 09:14:24 INFO - [mozharness: 2017-09-01 16:14:24.475620Z] Finished setup-mitmproxy step (success) 09:14:24 INFO - [mozharness: 2017-09-01 16:14:24.475837Z] Running run-tests step. 09:14:24 INFO - Running pre-action listener: _resource_record_pre_action 09:14:24 INFO - Running pre-action listener: _set_gcov_prefix 09:14:24 INFO - Running main action method: run_tests 09:14:24 WARNING - Try message not found. 09:14:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:14:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:14:24 INFO - Python 2.7.3 09:14:24 INFO - Return code: 0 09:14:24 INFO - grabbing minidump binary from tooltool 09:14:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:24 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 0x1425090>, '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 0x1425d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x141ff90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:14:24 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 09:14:24 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 09:14:24 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 09:14:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:14:24 INFO - Return code: 0 09:14:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:14:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:14:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:14:24 INFO - ENV: RUST_BACKTRACE is now full 09:14:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:14:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:14:24 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/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--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 09:14:24 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/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-049 --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 09:14:24 INFO - Using env: {'DISPLAY': ':0', 09:14:24 INFO - 'HOME': '/home/cltbld', 09:14:24 INFO - 'LANG': 'en_US.UTF-8', 09:14:24 INFO - 'LANGUAGE': 'en_US:en', 09:14:24 INFO - 'LOGNAME': 'cltbld', 09:14:24 INFO - 'MAIL': '/var/mail/cltbld', 09:14:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:14:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:14:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:24 INFO - 'MOZ_NO_REMOTE': '1', 09:14:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:14:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:24 INFO - 'NO_EM_RESTART': '1', 09:14:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:24 INFO - 'PWD': '/builds/slave/test', 09:14:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:14:24 INFO - 'RUST_BACKTRACE': 'full', 09:14:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:14:24 INFO - 'SHELL': '/bin/bash', 09:14:24 INFO - 'SHLVL': '1', 09:14:24 INFO - 'STYLO_FORCE_ENABLED': '1', 09:14:24 INFO - 'TERM': 'linux', 09:14:24 INFO - 'TMOUT': '86400', 09:14:24 INFO - 'USER': 'cltbld', 09:14:24 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', 09:14:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:24 INFO - '_': '/tools/buildbot/bin/python'} 09:14:24 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/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--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 09:14:24 INFO - mozversion application_buildid: 20170901150655 09:14:24 INFO - mozversion application_changeset: 34933f6390d52779ea498a6a5fd5f34d54734780 09:14:24 INFO - mozversion application_display_name: Nightly 09:14:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:14:24 INFO - mozversion application_name: Firefox 09:14:24 INFO - mozversion application_remotingname: firefox 09:14:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:14:24 INFO - mozversion application_vendor: Mozilla 09:14:24 INFO - mozversion application_version: 57.0a1 09:14:24 INFO - mozversion platform_buildid: 20170901150655 09:14:24 INFO - mozversion platform_changeset: 34933f6390d52779ea498a6a5fd5f34d54734780 09:14:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:14:24 INFO - mozversion platform_version: 57.0a1 09:14:24 INFO - using testdate: 1504282464 09:14:24 INFO - actual date: 1504282464 09:14:24 INFO - starting webserver on 'localhost:39058' 09:14:24 INFO - SUITE-START | Running 2 tests 09:14:24 INFO - TEST-START | dromaeo_css 09:14:24 INFO - Initialising browser for dromaeo_css test... 09:14:24 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39058/getInfo.html -profile /tmp/tmp44ijLi/profile 09:14:24 INFO - TEST-INFO | started process 7065 (/builds/slave/test/build/application/firefox/firefox http://localhost:39058/getInfo.html) 09:14:30 INFO - TEST-INFO | 7065: exit 0 09:14:30 INFO - Browser initialized. 09:14:30 INFO - Running cycle 1/1 for dromaeo_css test... 09:14:30 INFO - Using env: {'DISPLAY': ':0', 09:14:30 INFO - 'HOME': '/home/cltbld', 09:14:30 INFO - 'JSGC_DISABLE_POISONING': '1', 09:14:30 INFO - 'LANG': 'en_US.UTF-8', 09:14:30 INFO - 'LANGUAGE': 'en_US:en', 09:14:30 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 09:14:30 INFO - 'LOGNAME': 'cltbld', 09:14:30 INFO - 'MAIL': '/var/mail/cltbld', 09:14:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:14:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:14:30 INFO - 'MOZ_CRASHREPORTER': '1', 09:14:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:30 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 09:14:30 INFO - 'MOZ_NO_REMOTE': '1', 09:14:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:14:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:30 INFO - 'NO_EM_RESTART': '1', 09:14:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:30 INFO - 'PWD': '/builds/slave/test', 09:14:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:14:30 INFO - 'RUST_BACKTRACE': 'full', 09:14:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:14:30 INFO - 'SHELL': '/bin/bash', 09:14:30 INFO - 'SHLVL': '1', 09:14:30 INFO - 'STYLO_FORCE_ENABLED': '1', 09:14:30 INFO - 'TERM': 'linux', 09:14:30 INFO - 'TMOUT': '86400', 09:14:30 INFO - 'USER': 'cltbld', 09:14:30 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', 09:14:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:30 INFO - '_': '/tools/buildbot/bin/python'} 09:14:30 INFO - TEST-INFO | started process 7266 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp44ijLi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:14:31 INFO - PID 7266 | 09:14:31 INFO - PID 7266 | (/builds/slave/test/build/application/firefox/firefox:7317): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:14:31 INFO - PID 7266 | 09:14:41 INFO - PID 7266 | RSS: Main: 167628800 09:14:41 INFO - PID 7266 | 09:17:41 INFO - PID 7266 | Cycle 1(1): loaded http://localhost:39058/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39058/tests/dromaeo/cssquery-ext.html) 09:17:42 INFO - PID 7266 | RSS: Main: 172986368 09:17:42 INFO - PID 7266 | 09:20:44 INFO - PID 7266 | Cycle 1(1): loaded http://localhost:39058/tests/dromaeo/cssquery-ext.html (next: http://localhost:39058/tests/dromaeo/cssquery-jquery.html) 09:20:44 INFO - PID 7266 | RSS: Main: 169775104 09:20:44 INFO - PID 7266 | 09:23:46 INFO - PID 7266 | Cycle 1(1): loaded http://localhost:39058/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39058/tests/dromaeo/cssquery-mootools.html) 09:23:47 INFO - PID 7266 | RSS: Main: 172339200 09:23:47 INFO - PID 7266 | 09:26:48 INFO - PID 7266 | Cycle 1(1): loaded http://localhost:39058/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39058/tests/dromaeo/cssquery-prototype.html) 09:26:49 INFO - PID 7266 | RSS: Main: 170831872 09:26:49 INFO - PID 7266 | 09:29:53 INFO - PID 7266 | Cycle 1(1): loaded http://localhost:39058/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39058/tests/dromaeo/cssquery-yui.html) 09:29:53 INFO - PID 7266 | RSS: Main: 171692032 09:29:53 INFO - PID 7266 | 09:32:55 INFO - PID 7266 | Cycle 1(1): loaded http://localhost:39058/tests/dromaeo/cssquery-yui.html (next: http://localhost:39058/tests/dromaeo/cssquery-dojo.html) 09:32:55 INFO - PID 7266 | RSS: Main: 174944256 09:32:55 INFO - PID 7266 | 09:32:55 INFO - PID 7266 | __start_tp_report 09:32:55 INFO - PID 7266 | _x_x_mozilla_page_load 09:32:55 INFO - PID 7266 | _x_x_mozilla_page_load_details 09:32:55 INFO - PID 7266 | |i|pagename|runs| 09:32:55 INFO - PID 7266 | |0;dojo.html;3289.621693505246;3318.13728430608;3326.1518312830253;3342.398368293707;3360.865565377385;6366.172397588312;6397.904031439532;6406.327335629761;6421.165248517686;6480.12518310028;6410.96794516028;6543.2148142222995;6584.045313429549;6597.967010164943;6600.141981542392;5917.615355001922;5944.197533333009;6041.939580604182;6043.848903777419;6075.939240607604;5879.2063071485445;5879.353271140171;5891.234139561851;5936.3173235077875;5982.8803423931495;5912.2018527498885;5965.761369545213;5966;5971.820845374602;5992.34084250732;5863.941360586382;5920.851978700523;5968.283805943272;5968.7612495500125;6001.399860014008;5715.657060576337;5828.388019257959;5831.766729330821;5913.231279933581;5936.584439089265;6379.585326953733;6411.262704788963;6411.871762564722;6431.871362572722;6464.74141034358;6856.897146542806;6862.005009273627;6866.141732283465;6873.140857392826;6896.448284137257;6758.986152077178;6981.301869812978;6982.720691172347;6986.510944233905;7010.614416207107;6390.648514331663;6396.1045453636525;6471.4822814175695;6482.805515834532;6492.513061520358;6943.201531823905;7019.982102595095;7089.7873063808165;7092.787216383516;7104.822379440556;6790.694418751069;6792.422644075309;6876.484263680144;6887.965560172167;6892.104026476526;6921.7231310747;6964.408025317804;6970.546914450544;6993.720251189896;7001.264867188974;6871.931280687129;6951.6871740771785;6963.547369420971;6970.302969702989;6971.093757811452;6920.30796920304;6922.896156557656;6932.480063995221;6993.195782484978;7035.050268213726;5358.839234822962;5419.105847535191;5419.485148910847;5508.91736623951;5525.723713814293;4715.434147902273;4764.857054288377;4785.162596545661;4788.04239152164;4838.854834354975;5991.700414979321;6006;6065.8180254592435;6072.453479186895;6138.938610613926;6930.099087118642;6936.79189624312;6995.8600827983155;7038.577685338896;7045.11936007999;5938.8218353449465;6020.488258498077;6051.697415129226;6116;6122.051082082285;5943.524518038546;5954.583179177416;5959.821205363846;5960.910586341209;5975.37258587842;6604.438622717027;6696.06255124283;6699.497537684693;6699.631520266432;6701.731930722814;6886.380225779704;6899.517033807585;6949.68726407323;6969.477289203432;6969.616671082937;6586.341365863375;6630.801075967729;6661.666916654051;6710.765123220664;6738.966305168443;6737.764178253738;6799.456043516408;6801.319868013159;6829.965850170717;6866.279040700654;6919.584824910521;6948.444124469929;6956.756513522002;6959.338862808025;6965.895511567432;6677.866442671217;6691.665416729047;6694.531382803157;6698.363655452724;6698.5980841149685;5574.303212098488;6658.400743932875;6666.233383160805;6676.332366763285;6694.430973367318;6690.531662783558;6693.330666933462;6749.898751518829;6779.457643388419;6779.915213565806;6638.037484564615;6780.25417204117;6818.18181818185;6916.446684265146;6921.065656136559;6415.358464153547;6428.871422571428;6451.677416129269;6458.289588145394;6560.737570497317 09:32:55 INFO - PID 7266 | |1;ext.html;810.5866008335751;856.2978357746648;885.274075258288;888.2361169394323;1142.2232881640482;29471.852640736794;39091;39622;39650.20699586006;39691.20617587647;39602;39807.20385592293;39882.60117398825;39893.60106398935;39940.60059399405;7433.368163706092;7654.3111119999185;7662.770116896474;7668.194839541824;7670;7590.3548198403205;7680.923190768077;7682.193369696185;7683.193264707209;7691.730789422371;7650.770476885702;7704.537727736326;7709.7301594443925;7710.190430004853;7711.922880771177;7662.770116896474;7694.384449244075;7703.807404814868;7718.382529397691;7722.922770772277;7574.242575742437;7639.69441222356;7640.235976402371;7641.847163056764;7645.655945482466;35790.82104589487;35874.28251434983;35937.460938085685;35946.64053359459;35961.28077438463;3459.515667806509;3488.023353461035;3491.3715531204375;3492.2491664292297;3492.545969024036;30760.846195769103;30828.691713082808;30830.53754193689;30834.845825770954;30844.53733194004;5136.43499215086;5144.202648589475;5184.870378240536;5192.71440070796;5193.143131383355;183002;183156;183762.99999999866;184670;185653;44322.33516497255;45558.54441455543;45581.08837823291;45584.3162352565;45625.85935351576;46226.53773462289;46771.532284676716;46957.29564056542;46979.29531057037;47021.29468057982;13743.175409475463;13964;13978.370973306226;14711.705765884837;14829.77755333671;11222.214444988937;11310.660680179608;11319.26424782403;11322.830157547643;11323.886761132448;13189.40647670838;13243;13274.601761947157;13336.6665833353;13387.464501419834;5395.109806881899;5505.532029777433;5509.944900550943;5514.338279406416;5585.497305242548;999.9700008999741;1032.4785983078498;1038.8441733739849;1041.734357738787;1052.2529004406956;22580.435489112904;22645.886770566372;22711.659325110137;22791.430214244778;23000;1065.3022270412891;1101.4767985206963;1102.922795404314;1106.1758989552834;1129.1869853705318;11677.416129193507;11852.881471185177;11904.940475297568;11938.940305298418;11979.940100299442;230829;232842;233010;233361;233630;48635.27047094297;48807.51192488101;48991.510084899404;49044.50955490399;49098;1398.084254813099;1430.5279257845095;1430.6995530938411;1435.2751860310477;1447.7973083768281;21567.784322156578;21646.94354036364;21680.24119155822;21720.456988575414;22091.447713807283;34769.478457822646;34780.47829282512;34878;34971;45858.54141458543;213948;215873;216455;216863;217056;33759.83120084482;34108.658913410545;34115;34126.658733413344;34140.65859341375;39498.2100357997;39682.40476392799;40219.798901005306;40263.59736402598;40429.19141617092;30119.849400753734;30126;30272.84863575668;30663.84668076645;30692.232694182825;29460.852695736383;29756.256093597833;29845.70154298516;30044.699553005066;30351.848240758656;34027.48958765571;34097.48853767146;34142.82928585341;34167.31665366629;34208.82895585506;29081.563776543793;29144.125676229745;29281.707182927898;29291.267718307212;29555.113346599635;22370;22957.737324446647;23267.650985235574;23356.299311020695;23516.412089697893 09:32:55 INFO - PID 7266 | |2;jquery.html;557.8382269141952;557.8465921871482;563.2536888622574;584.2288179602924;593.3325009364465;21795.346139615798;22465.887670561624;22530.211442599513;22546.43633909153;24028.639570406456;25146.37134071649;25846.48307033858;25874.482510349782;25882;25900.35249118768;6459.677016149186;6604.27352991173;6610.504212184105;6612.570182938118;6615.404613584777;5885;6161.537884658646;6390.968045159768;6613.6693165341785;6616.900746488806;6593.769218077368;6607.5374723769355;6612.900806487906;6613.6693165341785;6618.404343609074;6570.868582628368;6592.241892182413;6596.472282217411;6596.703148358288;6601.2738598754095;6136.048912418582;6147.446729794296;6153.077038444225;6157.0456579230295;6159.784407545759;28016.579751303747;28389.290267743265;28827.135185944455;29299.853500732574;29426.558601620993;2042.5710600773855;2067.131804642054;2071.616750901073;2072.471519762466;2074.0770357191054;19262.807371926243;19889.701654475193;19895.20419183231;19903.50241243966;19907.601847963106;2386.246921212963;2413.746556611548;2462.409021834747;2489.4523204894895;2496.151308555082;128180;133459;133623;133982;134082;14709.41162353494;15068.397264109313;15178.924105379623;15234.847651523565;15380.38478460849;22197.445063873532;23369.88315058448;23371.182008629617;23414.882925585604;23495.647565286534;9801.11789938909;9822.557984890555;10012.69961901144;10058.949705251427;10112.039356261143;8998.910010899937;9023.233025192802;9030.27757779376;9087.591058402388;9124.771880703036;9362.689223508714;9402.576884040234;9538;9582.31695125366;9634.90365096354;2751.7660998815327;2764.737349951751;2768.207495899038;2800.341919648881;2883.7116288370994;966.3911735606523;972.1105188752213;982.6560703753629;994.9950250248702;1008.2186305613209;19268.940208288677;19996.700049499268;20534.281300154424;23077.307680769267;24088.15691450791;1017.2574020964737;1039.2309690828884;1058.8623478947688;1059.6979860739652;1063.106990128296;11051.61319353835;11259.605913792979;11333.206675532634;11345.205835591594;11399.601013964471;489962;509902;534760;538398;538898;114747;114849;116503.41748291205;117031.41484292694;117820.41089794497;1220.414201183437;1237.5297386992886;1242.8446444194476;1259.389196239823;1267.9746405071846;17830.5542361442;18104.72842907384;18174.909125454287;18418.907905460925;18862.905685471484;51361.22958155556;53039.20441193308;53296.4670353307;59902.40097598968;60845.087323689295;63325;67430;68326.97509537262;68968.6551567239;69703.65148174227;14502.637434064232;14779.039362441406;14952.177630230442;14996.100233985995;15012.92493537569;23332;23365.41586460352;23521.76478235196;23807.166749163694;23822.166224182765;18075.09624518795;19189;19230.134643940775;19239.711404329228;19388.515287117934;16584;16852.241649125575;17106.14469276556;17135.05757183367;17216.225269862636;19531.70702439436;19862.70205946883;20004.699929500777;20007.59984800325;20142.39572812818;14059.578212653636;14495.78256326135;14595.854041459874;14607.415703372171;14609.853901461274;18450.26198952027;18714.25142994265;18722.438326850217;18762.624747505248;18766.343177988703 09:32:55 INFO - PID 7266 | |3;mootools.html;1328.58813767732;1367.9452821887126;1382.4746596293407;1386.5840247925619;1389.1942673249516;14857.925710371446;15504.379824807022;15591;15599.45401910932;15605.84394156061;15480.5355839325;15755.921220393882;15783.921080394583;15836.129012904286;15837.287322070506;2358.363241924679;2757.269323629235;2829.25024868409;2830.391465834845;2832.4051949090717;2401.4356626192916;2690.6636670416196;2822.139247529503;2828.9292767680868;2832.4335132973383;2635.894564217429;2647.7087520372743;2661.7471340222646;2672.131557243894;2672.4521473097966;2678.370582913014;2702.3514356554483;2706.7022627511055;2719.8504082275467;2721.319670082479;2624.4619852930105;2646.1532309660915;2655.2963464681875;2655.3892604701027;2656.6546348974703;15200.771988420183;15263.465778697804;15399.615009624737;15401.845981540153;15414.614634634112;7802.180771019018;8140.063892652362;8153.1439198884445;8154.633041513087;8158.755237342889;5582;5584.21820945068;5585.888282234332;5747.3103227612955;5880.147378630075;1971.122850331603;1972.5956178983274;1987.3739771971782;2022.5853699991615;2026.2604149485478;27065.45869082627;27099.1870243893;27262.182134535997;27307.86346068277;27450.313742156406;6566.343365663395;6658.9001164982565;6702.631355275506;6839.863202736017;6860.725570977105;17549.122543872756;17631.559211019827;17639.470815875287;17677.7348339775;17690.292388304322;7828.804279893048;8019.438639295194;8028.518288902685;8029.357651387992;8037.638306276232;7285.089363829521;7399.704011839466;7460.589667568336;7478.140013898471;7483.289087536676;5299.4700529946695;5415;5452.236686863842;5474.233607294982;5502.202180683778;1561.7501199807975;1570.57594449498;1573.087609186669;1575.94484193055;1579.155151993678;853.9231469167805;874.7200895713438;889.7953470701646;891.9018907920124;893.3657103456619;7253.5855508176455;7845.803854903673;8149.877751833727;8156.9184308156155;8248.917510824935;726.6875243645284;734.071399679406;737.7491652837954;740.4742632730714;753.6231884057971;4371.759553224603;4406.162829062468;4431.379606855043;4432.357308190294;4448.266036104072;20878.164873404894;21340.253091141734;21664.675029874565;21666.35000949974;21934.670979935312;20879.26922557703;22408.55182896333;22427.102915883508;22428.32715018552;22453.21413750511;10744.838827417596;11393.487293071832;11753.4710938008;11756.647300580997;11778.11664125194;8786.956065219632;8991.100889910957;9037.231835294073;9125.954370228106;9136.588853501475;18453.446396608124;18762.343317983807;19037.714434283775;19232.61534769269;19457.318993835146;19087.8091219086;19524.8047519523;19767.20931162795;19776.505587359854;19791.109400076744;12216.389180541115;12292.508299667914;12400.937995310327;12479.126461147633;12489.875101248872;13970;14093.224872632103;14113.2237726926;14128.011039227156;14133.717325653635;11404.885951140383;11518.712032199262;11606.70983225426;11637.825432618687;11721.765564688829;11004.119670426508;11054.889451105386;11112.22214444981;11401.3729244889;11431;9513.571889265138;9579;9604.135627793532;9605.567749451151;9610.711678649652;6897.241303456716;6909;6930.3762661358705;6941.34057264559;6987.615681137586;6956.965215173892;7006.439484841302;7035.401990830836;7054;7058.858822823412 09:32:55 INFO - PID 7266 | |4;prototype.html;499.89772551523873;513.9977044762713;523.1446584833797;526.4577485190256;535.9040761642441;6347.5556711030185;6367;6384.297727249998;6389.137466442035;6401.839954001152;5987.311459182195;6155.784547540837;6202.81391558254;6272.153259309992;6274.623522588648;4921.876953076175;4967.900641987158;5017;5020.4226513951;5065.037642847866;4878.219484882419;4938.901221975559;5006.599472042228;5040.6471546991725;5087.287779710843;4941.50454020719;4948.134076536592;5024.924626130611;5051.419086805027;5057.190849464087;4988;4999.725015124167;5018.999999999982;5053.343065401493;5097.439281679012;4462.263726485126;4473.46318441789;4499.302608095718;4503;4546.431696037996;6179.62922224668;6242.6878656067465;6248.812535623893;6268.341824108494;6284.2458904931245;5780.855478613068;5785.334686511063;5796.97101514494;5819.243498345188;5823.155642431866;6495.577787443848;6589.143411356493;6660.333966603349;6711.194656641185;6713.597184168965;4914;4918.754062296871;4921.729304888229;4929.556339929423;4945.431275403339;6936;6979.546329488618;7012.298770122947;7024.402925751267;7092;5001.64988450805;5026.497350265017;5112.565431938327;5120.7951681933055;5134.87162820925;5076.7207803571155;5106.872328191825;5111.846644600668;5116.462771409023;5158.63889527737;5749.8562535936935;5750.396208398058;5756.740946657326;5757.890739000976;5777.335606405233;6680.331966803281;6701.865962680719;6725.529212955046;6737.551426443339;6760.898586521206;1649.3320205316627;1699.566610514311;1772.503698964292;1782.5989152440598;1786.7945186303482;1192.9403529823649;1217.6773155113901;1218.7562487502357;1235.882591153839;1247.8065899785547;5745.770169193269;5827.096799996008;5870.647761134345;5891.793787217427;5897.823065307962;6490.5781124227715;6539.640319782363;6561.934380656227;6585.916731626422;6710.731570737214;5750.568707346882;5765.279340082489;5781.993363450982;5865.824025279248;5870.853228669317;3535.734819888519;3564.91087722809;3573.6069032406413;3596.5504311961004;3598.6221446747713;6435;6480.416762491398;6491.772787952494;6566;6569.37590928861;6800.353966373186;6812.795616131524;6830.829229269308;6875.690593923286;6954.478414118961;6407.199100112486;6428.71070801815;6449.5485316027425;6552.8361790955605;6564.538298138198;6613.6693165340575;6659.701358235291;6687.121469547748;6744.190697116377;6773.62745049027;6569.441597464269;6589.505787065893;6633.303503132294;6678.3989440950545;6708.597484150967;6400.51996100285;6408.839779005562;6498.805035848932;6499.805005849832;6519.380658837403;4952.380952380952;4992.051510213048;5021.598272138147;5022.924656130088;5039.924401134059;6674.130124046706;6742.190937087385;6752.5948443093575;6757.966210168917;6763.391294783493;6516.869662606627;6561.639109849004;6588.8023359299295;6596.888995521387;6618.551136079682;6461.903071454026;6495.863223935887;6532.5753826001155;6564.573302735307;6569.835754106186;5889.499392551681;6407.238009547412;6427.710753016219;6517.478601711757;6517.902231466626;6569.868602628017;6607.339266073354;6617.338266173537;6658.400743933069;6670.633115178712;6277.309495955533;6279.96860015697;6298.433141017331;6349.682515874096;6440.967795160994 09:32:55 INFO - PID 7266 | |5;yui.html;891.9955400222998;908.9590968406421;919.5264438814007;921.0329154387891;939.2204470289659;16584;17020;17106.230219640114;17146.142692865353;21077.15691372347;19261.325853595128;21078.15687372503;21661.783382166213;21675.241366552174;21676.674849877265;905.7282815155477;916.8533034714447;922.7001224601997;924.3945215883607;924.875141855851;928.2759447630858;932.6036434515338;934.1639232886563;934.887813462382;935.550935550936;986.57577241786;986.9506524673774;987.3483500889414;989.8911119776819;991.6876184002052;977.6969139566758;979.9363041402322;980.6959842448828;980.9068138526828;982.6315131825565;726.9745558905465;729.0267492896988;729.3071581997071;729.8102493351714;730.1785491322263;18498.2600695972;18898.622027559508;19188.328408505775;19720.21119155232;19738;21179.152833886626;21445.142194312208;21670.566588668138;21691.566168676694;21908.780912190992;16118.75821862673;16662.083585402797;16690.58273543159;16922.238499267438;17646.558836029202;3850.2299540092095;3883.10688541637;3886.514185726784;3893.3186692329014;3894.1432884765304;123786;123980.38009809983;125088.37455812663;127410.99999999907;128786;15441.382344706311;15559.22203889801;15721;15922.203889805463;15995.520134395987;30151.396972060873;30178.396432071237;30299.394012119636;30880.69119308823;30940.535891961637;7497.1753107158165;7834.373250139975;8491.490510569385;8576.699815506427;8592;4186.748795072305;4243.172581346669;4249.936250956238;4251.744895306291;4272.978635106804;5188.11801993662;5230.476952304739;5247.239150323182;5267.762950667229;5280.577553795687;1035.8601588785568;1056.3661802918189;1060.6181774561153;1063.8829728729834;1067.3275836223129;680.1396233764292;690.0925283252507;692.2765709833236;697.6616341074575;703.4372501998381;11298.435078246055;11674.182807203415;11687.824682629767;11787.823182652268;12242.510299588095;651.3974573519475;657.4641667041346;667.2726727866649;668.8227619680788;673.9856515943571;5740.626859254218;5990.7903223386975;6011.308699499614;6496.675166241669;6612.305707900698;109776.4511177455;111542.44228778966;112661;112706;113231.4338428319;100924;103605;103716.48141759394;103847.48076259722;104375;84613;84632.57683711665;86890.56554717313;88213;88550.55724721335;11884.465199066235;11943.522259109539;12057.75884482288;12171.573994910135;12181.75636487283;41352.37971430371;41396.586034139276;41723;41996.580034199265;42551.7872410636;53808;53975;54117;54248.728756355966;56033;18291.26834926588;18622.627547449247;18644.720329194803;18678.25286988505;18767.624647506702;20868;20940.05769740335;20960.790392095885;21101.366958991253;21169.25907593227;18949.43151705451;19036.619267614846;19195.520111997314;19205.039748012263;19280;19277.421677349703;19618.215271388985;19623.215071397557;19696.21215151435;19712.21151153938;17332.480025599252;17588.73616895773;17609.29562817524;17652.73520897213;17662.381816636356;12677.429515671633;13041.152325098838;13215.867841321464;13447.193168409927;13908.374123164684;14690.485832995793;14983.775243371576;15129.778526601338;15141.394344226108;15145.318460669076 09:32:55 INFO - PID 7266 | __end_tp_report 09:32:55 INFO - PID 7266 | __start_cc_report 09:32:55 INFO - PID 7266 | _x_x_mozilla_cycle_collect,391 09:32:55 INFO - PID 7266 | __end_cc_report 09:32:55 INFO - PID 7266 | __startTimestamp1504283575641__endTimestamp 09:32:55 INFO - PID 7266 | 09:32:55 INFO - PID 7266 | ------- Summary: start ------- 09:32:55 INFO - PID 7266 | Number of tests: 6 09:32:55 INFO - PID 7266 | 09:32:55 INFO - PID 7266 | [#0] dojo.html Cycles:165 Average:6360.41 Median:6604.44 stddev:750.44 (11.4%) stddev-sans-first:713.03 09:32:55 INFO - PID 7266 | Values: 3289.6 3318.1 3326.2 3342.4 3360.9 6366.2 6397.9 6406.3 6421.2 6480.1 6411.0 6543.2 6584.0 6598.0 6600.1 5917.6 5944.2 6041.9 6043.8 6075.9 5879.2 5879.4 5891.2 5936.3 5982.9 5912.2 5965.8 5966.0 5971.8 5992.3 5863.9 5920.9 5968.3 5968.8 6001.4 5715.7 5828.4 5831.8 5913.2 5936.6 6379.6 6411.3 6411.9 6431.9 6464.7 6856.9 6862.0 6866.1 6873.1 6896.4 6759.0 6981.3 6982.7 6986.5 7010.6 6390.6 6396.1 6471.5 6482.8 6492.5 6943.2 7020.0 7089.8 7092.8 7104.8 6790.7 6792.4 6876.5 6888.0 6892.1 6921.7 6964.4 6970.5 6993.7 7001.3 6871.9 6951.7 6963.5 6970.3 6971.1 6920.3 6922.9 6932.5 6993.2 7035.1 5358.8 5419.1 5419.5 5508.9 5525.7 4715.4 4764.9 4785.2 4788.0 4838.9 5991.7 6006.0 6065.8 6072.5 6138.9 6930.1 6936.8 6995.9 7038.6 7045.1 5938.8 6020.5 6051.7 6116.0 6122.1 5943.5 5954.6 5959.8 5960.9 5975.4 6604.4 6696.1 6699.5 6699.6 6701.7 6886.4 6899.5 6949.7 6969.5 6969.6 6586.3 6630.8 6661.7 6710.8 6739.0 6737.8 6799.5 6801.3 6830.0 6866.3 6919.6 6948.4 6956.8 6959.3 6965.9 6677.9 6691.7 6694.5 6698.4 6698.6 5574.3 6658.4 6666.2 6676.3 6694.4 6690.5 6693.3 6749.9 6779.5 6779.9 6638.0 6780.3 6818.2 6916.4 6921.1 6415.4 6428.9 6451.7 6458.3 6560.7 09:32:55 INFO - PID 7266 | 09:32:55 INFO - PID 7266 | [#1] ext.html Cycles:180 Average:36886.98 Median:34120.83 stddev:54962.73 (161.1%) stddev-sans-first:55050.17 09:32:55 INFO - PID 7266 | Values: 810.6 856.3 885.3 888.2 1142.2 29471.9 39091.0 39622.0 39650.2 39691.2 39602.0 39807.2 39882.6 39893.6 39940.6 7433.4 7654.3 7662.8 7668.2 7670.0 7590.4 7680.9 7682.2 7683.2 7691.7 7650.8 7704.5 7709.7 7710.2 7711.9 7662.8 7694.4 7703.8 7718.4 7722.9 7574.2 7639.7 7640.2 7641.8 7645.7 35790.8 35874.3 35937.5 35946.6 35961.3 3459.5 3488.0 3491.4 3492.2 3492.5 30760.8 30828.7 30830.5 30834.8 30844.5 5136.4 5144.2 5184.9 5192.7 5193.1 183002.0 183156.0 183763.0 184670.0 185653.0 44322.3 45558.5 45581.1 45584.3 45625.9 46226.5 46771.5 46957.3 46979.3 47021.3 13743.2 13964.0 13978.4 14711.7 14829.8 11222.2 11310.7 11319.3 11322.8 11323.9 13189.4 13243.0 13274.6 13336.7 13387.5 5395.1 5505.5 5509.9 5514.3 5585.5 1000.0 1032.5 1038.8 1041.7 1052.3 22580.4 22645.9 22711.7 22791.4 23000.0 1065.3 1101.5 1102.9 1106.2 1129.2 11677.4 11852.9 11904.9 11938.9 11979.9 230829.0 232842.0 233010.0 233361.0 233630.0 48635.3 48807.5 48991.5 49044.5 49098.0 1398.1 1430.5 1430.7 1435.3 1447.8 21567.8 21646.9 21680.2 21720.5 22091.4 34769.5 34780.5 34878.0 34971.0 45858.5 213948.0 215873.0 216455.0 216863.0 217056.0 33759.8 34108.7 34115.0 34126.7 34140.7 39498.2 39682.4 40219.8 40263.6 40429.2 30119.8 30126.0 30272.8 30663.8 30692.2 29460.9 29756.3 29845.7 30044.7 30351.8 34027.5 34097.5 34142.8 34167.3 34208.8 29081.6 29144.1 29281.7 29291.3 29555.1 22370.0 22957.7 23267.7 23356.3 23516.4 09:32:55 INFO - PID 7266 | 09:32:55 INFO - PID 7266 | [#2] jquery.html Cycles:180 Average:35558.62 Median:23370.53 stddev:87405.82 (374.0%) stddev-sans-first:87611.51 09:32:55 INFO - PID 7266 | Values: 557.8 557.8 563.3 584.2 593.3 21795.3 22465.9 22530.2 22546.4 24028.6 25146.4 25846.5 25874.5 25882.0 25900.4 6459.7 6604.3 6610.5 6612.6 6615.4 5885.0 6161.5 6391.0 6613.7 6616.9 6593.8 6607.5 6612.9 6613.7 6618.4 6570.9 6592.2 6596.5 6596.7 6601.3 6136.0 6147.4 6153.1 6157.0 6159.8 28016.6 28389.3 28827.1 29299.9 29426.6 2042.6 2067.1 2071.6 2072.5 2074.1 19262.8 19889.7 19895.2 19903.5 19907.6 2386.2 2413.7 2462.4 2489.5 2496.2 128180.0 133459.0 133623.0 133982.0 134082.0 14709.4 15068.4 15178.9 15234.8 15380.4 22197.4 23369.9 23371.2 23414.9 23495.6 9801.1 9822.6 10012.7 10058.9 10112.0 8998.9 9023.2 9030.3 9087.6 9124.8 9362.7 9402.6 9538.0 9582.3 9634.9 2751.8 2764.7 2768.2 2800.3 2883.7 966.4 972.1 982.7 995.0 1008.2 19268.9 19996.7 20534.3 23077.3 24088.2 1017.3 1039.2 1058.9 1059.7 1063.1 11051.6 11259.6 11333.2 11345.2 11399.6 489962.0 509902.0 534760.0 538398.0 538898.0 114747.0 114849.0 116503.4 117031.4 117820.4 1220.4 1237.5 1242.8 1259.4 1268.0 17830.6 18104.7 18174.9 18418.9 18862.9 51361.2 53039.2 53296.5 59902.4 60845.1 63325.0 67430.0 68327.0 68968.7 69703.7 14502.6 14779.0 14952.2 14996.1 15012.9 23332.0 23365.4 23521.8 23807.2 23822.2 18075.1 19189.0 19230.1 19239.7 19388.5 16584.0 16852.2 17106.1 17135.1 17216.2 19531.7 19862.7 20004.7 20007.6 20142.4 14059.6 14495.8 14595.9 14607.4 14609.9 18450.3 18714.3 18722.4 18762.6 18766.3 09:32:55 INFO - PID 7266 | 09:32:55 INFO - PID 7266 | [#3] mootools.html Cycles:180 Average:9459.27 Median:2672.29 stddev:6855.91 (256.6%) stddev-sans-first:6847.92 09:32:55 INFO - PID 7266 | Values: 1328.6 1367.9 1382.5 1386.6 1389.2 14857.9 15504.4 15591.0 15599.5 15605.8 15480.5 15755.9 15783.9 15836.1 15837.3 2358.4 2757.3 2829.3 2830.4 2832.4 2401.4 2690.7 2822.1 2828.9 2832.4 2635.9 2647.7 2661.7 2672.1 2672.5 2678.4 2702.4 2706.7 2719.9 2721.3 2624.5 2646.2 2655.3 2655.4 2656.7 15200.8 15263.5 15399.6 15401.8 15414.6 7802.2 8140.1 8153.1 8154.6 8158.8 5582.0 5584.2 5585.9 5747.3 5880.1 1971.1 1972.6 1987.4 2022.6 2026.3 27065.5 27099.2 27262.2 27307.9 27450.3 6566.3 6658.9 6702.6 6839.9 6860.7 17549.1 17631.6 17639.5 17677.7 17690.3 7828.8 8019.4 8028.5 8029.4 8037.6 7285.1 7399.7 7460.6 7478.1 7483.3 5299.5 5415.0 5452.2 5474.2 5502.2 1561.8 1570.6 1573.1 1575.9 1579.2 853.9 874.7 889.8 891.9 893.4 7253.6 7845.8 8149.9 8156.9 8248.9 726.7 734.1 737.7 740.5 753.6 4371.8 4406.2 4431.4 4432.4 4448.3 20878.2 21340.3 21664.7 21666.4 21934.7 20879.3 22408.6 22427.1 22428.3 22453.2 10744.8 11393.5 11753.5 11756.6 11778.1 8787.0 8991.1 9037.2 9126.0 9136.6 18453.4 18762.3 19037.7 19232.6 19457.3 19087.8 19524.8 19767.2 19776.5 19791.1 12216.4 12292.5 12400.9 12479.1 12489.9 13970.0 14093.2 14113.2 14128.0 14133.7 11404.9 11518.7 11606.7 11637.8 11721.8 11004.1 11054.9 11112.2 11401.4 11431.0 9513.6 9579.0 9604.1 9605.6 9610.7 6897.2 6909.0 6930.4 6941.3 6987.6 6957.0 7006.4 7035.4 7054.0 7058.9 09:32:55 INFO - PID 7266 | 09:32:55 INFO - PID 7266 | [#4] prototype.html Cycles:180 Average:5540.11 Median:6245.75 stddev:1551.56 (24.8%) stddev-sans-first:1509.09 09:32:55 INFO - PID 7266 | Values: 499.9 514.0 523.1 526.5 535.9 6347.6 6367.0 6384.3 6389.1 6401.8 5987.3 6155.8 6202.8 6272.2 6274.6 4921.9 4967.9 5017.0 5020.4 5065.0 4878.2 4938.9 5006.6 5040.6 5087.3 4941.5 4948.1 5024.9 5051.4 5057.2 4988.0 4999.7 5019.0 5053.3 5097.4 4462.3 4473.5 4499.3 4503.0 4546.4 6179.6 6242.7 6248.8 6268.3 6284.2 5780.9 5785.3 5797.0 5819.2 5823.2 6495.6 6589.1 6660.3 6711.2 6713.6 4914.0 4918.8 4921.7 4929.6 4945.4 6936.0 6979.5 7012.3 7024.4 7092.0 5001.6 5026.5 5112.6 5120.8 5134.9 5076.7 5106.9 5111.8 5116.5 5158.6 5749.9 5750.4 5756.7 5757.9 5777.3 6680.3 6701.9 6725.5 6737.6 6760.9 1649.3 1699.6 1772.5 1782.6 1786.8 1192.9 1217.7 1218.8 1235.9 1247.8 5745.8 5827.1 5870.6 5891.8 5897.8 6490.6 6539.6 6561.9 6585.9 6710.7 5750.6 5765.3 5782.0 5865.8 5870.9 3535.7 3564.9 3573.6 3596.6 3598.6 6435.0 6480.4 6491.8 6566.0 6569.4 6800.4 6812.8 6830.8 6875.7 6954.5 6407.2 6428.7 6449.5 6552.8 6564.5 6613.7 6659.7 6687.1 6744.2 6773.6 6569.4 6589.5 6633.3 6678.4 6708.6 6400.5 6408.8 6498.8 6499.8 6519.4 4952.4 4992.1 5021.6 5022.9 5039.9 6674.1 6742.2 6752.6 6758.0 6763.4 6516.9 6561.6 6588.8 6596.9 6618.6 6461.9 6495.9 6532.6 6564.6 6569.8 5889.5 6407.2 6427.7 6517.5 6517.9 6569.9 6607.3 6617.3 6658.4 6670.6 6277.3 6280.0 6298.4 6349.7 6441.0 09:32:55 INFO - PID 7266 | 09:32:55 INFO - PID 7266 | [#5] yui.html Cycles:180 Average:23615.30 Median:21672.90 stddev:32122.95 (148.2%) stddev-sans-first:32167.74 09:32:55 INFO - PID 7266 | Values: 892.0 909.0 919.5 921.0 939.2 16584.0 17020.0 17106.2 17146.1 21077.2 19261.3 21078.2 21661.8 21675.2 21676.7 905.7 916.9 922.7 924.4 924.9 928.3 932.6 934.2 934.9 935.6 986.6 987.0 987.3 989.9 991.7 977.7 979.9 980.7 980.9 982.6 727.0 729.0 729.3 729.8 730.2 18498.3 18898.6 19188.3 19720.2 19738.0 21179.2 21445.1 21670.6 21691.6 21908.8 16118.8 16662.1 16690.6 16922.2 17646.6 3850.2 3883.1 3886.5 3893.3 3894.1 123786.0 123980.4 125088.4 127411.0 128786.0 15441.4 15559.2 15721.0 15922.2 15995.5 30151.4 30178.4 30299.4 30880.7 30940.5 7497.2 7834.4 8491.5 8576.7 8592.0 4186.7 4243.2 4249.9 4251.7 4273.0 5188.1 5230.5 5247.2 5267.8 5280.6 1035.9 1056.4 1060.6 1063.9 1067.3 680.1 690.1 692.3 697.7 703.4 11298.4 11674.2 11687.8 11787.8 12242.5 651.4 657.5 667.3 668.8 674.0 5740.6 5990.8 6011.3 6496.7 6612.3 109776.5 111542.4 112661.0 112706.0 113231.4 100924.0 103605.0 103716.5 103847.5 104375.0 84613.0 84632.6 86890.6 88213.0 88550.6 11884.5 11943.5 12057.8 12171.6 12181.8 41352.4 41396.6 41723.0 41996.6 42551.8 53808.0 53975.0 54117.0 54248.7 56033.0 18291.3 18622.6 18644.7 18678.3 18767.6 20868.0 20940.1 20960.8 21101.4 21169.3 18949.4 19036.6 19195.5 19205.0 19280.0 19277.4 19618.2 19623.2 19696.2 19712.2 17332.5 17588.7 17609.3 17652.7 17662.4 12677.4 13041.2 13215.9 13447.2 13908.4 14690.5 14983.8 15129.8 15141.4 15145.3 09:32:55 INFO - PID 7266 | -------- Summary: end -------- 09:32:55 INFO - PID 7266 | 09:32:56 INFO - TEST-INFO | 7266: exit 0 09:32:56 INFO - TEST-OK | dromaeo_css | took 1111603ms 09:32:56 INFO - TEST-START | kraken 09:32:56 INFO - Initialising browser for kraken test... 09:32:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39058/getInfo.html -profile /tmp/tmptCNJCZ/profile 09:32:56 INFO - TEST-INFO | started process 7643 (/builds/slave/test/build/application/firefox/firefox http://localhost:39058/getInfo.html) 09:33:01 INFO - TEST-INFO | 7643: exit 0 09:33:01 INFO - Browser initialized. 09:33:01 INFO - Running cycle 1/1 for kraken test... 09:33:01 INFO - Using env: {'DISPLAY': ':0', 09:33:01 INFO - 'HOME': '/home/cltbld', 09:33:01 INFO - 'JSGC_DISABLE_POISONING': '1', 09:33:01 INFO - 'LANG': 'en_US.UTF-8', 09:33:01 INFO - 'LANGUAGE': 'en_US:en', 09:33:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 09:33:01 INFO - 'LOGNAME': 'cltbld', 09:33:01 INFO - 'MAIL': '/var/mail/cltbld', 09:33:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:33:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:33:01 INFO - 'MOZ_CRASHREPORTER': '1', 09:33:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 09:33:01 INFO - 'MOZ_NO_REMOTE': '1', 09:33:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:33:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:01 INFO - 'NO_EM_RESTART': '1', 09:33:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:01 INFO - 'PWD': '/builds/slave/test', 09:33:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:33:01 INFO - 'RUST_BACKTRACE': 'full', 09:33:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:33:01 INFO - 'SHELL': '/bin/bash', 09:33:01 INFO - 'SHLVL': '1', 09:33:01 INFO - 'STYLO_FORCE_ENABLED': '1', 09:33:01 INFO - 'TERM': 'linux', 09:33:01 INFO - 'TMOUT': '86400', 09:33:01 INFO - 'USER': 'cltbld', 09:33:01 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869', 09:33:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:01 INFO - '_': '/tools/buildbot/bin/python'} 09:33:01 INFO - TEST-INFO | started process 7836 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptCNJCZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:33:02 INFO - PID 7836 | 09:33:02 INFO - PID 7836 | (/builds/slave/test/build/application/firefox/firefox:7887): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:33:02 INFO - PID 7836 | 09:33:13 INFO - PID 7836 | RSS: Main: 172412928 09:33:13 INFO - PID 7836 | 09:33:15 INFO - PID 7836 | Cycle 1(1): loaded http://localhost:39058/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39058/tests/kraken/driver.html?testName=audio-beat-detection) 09:33:16 INFO - PID 7836 | RSS: Main: 177147904 09:33:16 INFO - PID 7836 | 09:33:19 INFO - PID 7836 | Cycle 1(1): loaded http://localhost:39058/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39058/tests/kraken/driver.html?testName=audio-dft) 09:33:19 INFO - PID 7836 | RSS: Main: 177172480 09:33:19 INFO - PID 7836 | 09:33:22 INFO - PID 7836 | Cycle 1(1): loaded http://localhost:39058/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39058/tests/kraken/driver.html?testName=audio-fft) 09:33:23 INFO - PID 7836 | RSS: Main: 171802624 09:33:23 INFO - PID 7836 | 09:33:25 INFO - PID 7836 | Cycle 1(1): loaded http://localhost:39058/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39058/tests/kraken/driver.html?testName=audio-oscillator) 09:33:26 INFO - PID 7836 | RSS: Main: 172503040 09:33:26 INFO - PID 7836 | 09:33:28 INFO - PID 7836 | Cycle 1(1): loaded http://localhost:39058/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39058/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:33:29 INFO - PID 7836 | RSS: Main: 172830720 09:33:29 INFO - PID 7836 | 09:33:32 INFO - PID 7836 | Cycle 1(1): loaded http://localhost:39058/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39058/tests/kraken/driver.html?testName=imaging-darkroom) 09:33:32 INFO - PID 7836 | RSS: Main: 175235072 09:33:32 INFO - PID 7836 | 09:33:36 INFO - PID 7836 | Cycle 1(1): loaded http://localhost:39058/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39058/tests/kraken/driver.html?testName=imaging-desaturate) 09:33:36 INFO - PID 7836 | RSS: Main: 175546368 09:33:36 INFO - PID 7836 | 09:33:39 INFO - PID 7836 | Cycle 1(1): loaded http://localhost:39058/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39058/tests/kraken/driver.html?testName=json-parse-financial) 09:33:40 INFO - PID 7836 | RSS: Main: 174854144 09:33:40 INFO - PID 7836 | 09:33:42 INFO - PID 7836 | Cycle 1(1): loaded http://localhost:39058/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39058/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:33:42 INFO - PID 7836 | RSS: Main: 175366144 09:33:42 INFO - PID 7836 | 09:33:44 INFO - PID 7836 | Cycle 1(1): loaded http://localhost:39058/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39058/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:33:45 INFO - PID 7836 | RSS: Main: 176062464 09:33:45 INFO - PID 7836 | 09:33:47 INFO - PID 7836 | Cycle 1(1): loaded http://localhost:39058/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39058/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:33:47 INFO - PID 7836 | RSS: Main: 172675072 09:33:47 INFO - PID 7836 | 09:33:50 INFO - PID 7836 | Cycle 1(1): loaded http://localhost:39058/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39058/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:33:51 INFO - PID 7836 | RSS: Main: 172957696 09:33:51 INFO - PID 7836 | 09:33:54 INFO - PID 7836 | Cycle 1(1): loaded http://localhost:39058/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39058/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:33:54 INFO - PID 7836 | RSS: Main: 173268992 09:33:54 INFO - PID 7836 | 09:33:56 INFO - PID 7836 | Cycle 1(1): loaded http://localhost:39058/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39058/tests/kraken/driver.html?testName=ai-astar) 09:33:56 INFO - PID 7836 | RSS: Main: 174452736 09:33:56 INFO - PID 7836 | 09:33:56 INFO - PID 7836 | __start_tp_report 09:33:56 INFO - PID 7836 | _x_x_mozilla_page_load 09:33:56 INFO - PID 7836 | _x_x_mozilla_page_load_details 09:33:56 INFO - PID 7836 | |i|pagename|runs| 09:33:56 INFO - PID 7836 | |0;ai-astar;98;107;99;107;106;116;108;98;106;109 09:33:56 INFO - PID 7836 | |1;audio-beat-detection;150;115;140;167;115;146;142;118;190;120 09:33:56 INFO - PID 7836 | |2;audio-dft;165;162;162;163;164;162;162;164;174;170 09:33:56 INFO - PID 7836 | |3;audio-fft;74;80;73;179;74;75;74;73;152;75 09:33:56 INFO - PID 7836 | |4;audio-oscillator;80;79;138;79;80;80;123;79;80;121 09:33:56 INFO - PID 7836 | |5;imaging-gaussian-blur;114;114;115;114;113;114;114;114;115;113 09:33:56 INFO - PID 7836 | |6;imaging-darkroom;148;148;149;148;147;148;148;149;147;148 09:33:56 INFO - PID 7836 | |7;imaging-desaturate;93;93;95;93;94;94;94;94;94;94 09:33:56 INFO - PID 7836 | |8;json-parse-financial;53;54;54;55;54;55;54;53;54;54 09:33:56 INFO - PID 7836 | |9;json-stringify-tinderbox;38;39;38;39;39;39;38;39;38;38 09:33:56 INFO - PID 7836 | |10;stanford-crypto-aes;76;78;74;74;79;86;75;75;75;79 09:33:56 INFO - PID 7836 | |11;stanford-crypto-ccm;121;127;142;120;120;115;124;115;123;164 09:33:56 INFO - PID 7836 | |12;stanford-crypto-pbkdf2;176;170;175;171;174;172;181;182;177;171 09:33:56 INFO - PID 7836 | |13;stanford-crypto-sha256-iterative;66;65;68;71;64;64;63;65;63;63 09:33:56 INFO - PID 7836 | __end_tp_report 09:33:56 INFO - PID 7836 | __start_cc_report 09:33:56 INFO - PID 7836 | _x_x_mozilla_cycle_collect,937 09:33:56 INFO - PID 7836 | __end_cc_report 09:33:56 INFO - PID 7836 | __startTimestamp1504283636781__endTimestamp 09:33:56 INFO - PID 7836 | 09:33:56 INFO - PID 7836 | ------- Summary: start ------- 09:33:56 INFO - PID 7836 | Number of tests: 14 09:33:56 INFO - PID 7836 | 09:33:56 INFO - PID 7836 | [#0] ai-astar Cycles:10 Average:105.40 Median:112.50 stddev:5.66 (5.0%) stddev-sans-first:5.33 09:33:56 INFO - PID 7836 | Values: 98.0 107.0 99.0 107.0 106.0 116.0 108.0 98.0 106.0 109.0 09:33:56 INFO - PID 7836 | 09:33:56 INFO - PID 7836 | [#1] audio-beat-detection Cycles:10 Average:140.30 Median:144.00 stddev:24.73 (17.2%) stddev-sans-first:25.97 09:33:56 INFO - PID 7836 | Values: 150.0 115.0 140.0 167.0 115.0 146.0 142.0 118.0 190.0 120.0 09:33:56 INFO - PID 7836 | 09:33:56 INFO - PID 7836 | [#2] audio-dft Cycles:10 Average:164.80 Median:164.00 stddev:4.05 (2.5%) stddev-sans-first:4.29 09:33:56 INFO - PID 7836 | Values: 165.0 162.0 162.0 163.0 164.0 162.0 162.0 164.0 174.0 170.0 09:33:56 INFO - PID 7836 | 09:33:56 INFO - PID 7836 | [#3] audio-fft Cycles:10 Average:92.90 Median:74.00 stddev:38.84 (52.5%) stddev-sans-first:40.59 09:33:56 INFO - PID 7836 | Values: 74.0 80.0 73.0 179.0 74.0 75.0 74.0 73.0 152.0 75.0 09:33:56 INFO - PID 7836 | 09:33:56 INFO - PID 7836 | [#4] audio-oscillator Cycles:10 Average:93.90 Median:79.50 stddev:23.49 (29.5%) stddev-sans-first:24.37 09:33:56 INFO - PID 7836 | Values: 80.0 79.0 138.0 79.0 80.0 80.0 123.0 79.0 80.0 121.0 09:33:56 INFO - PID 7836 | 09:33:56 INFO - PID 7836 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 09:33:56 INFO - PID 7836 | Values: 114.0 114.0 115.0 114.0 113.0 114.0 114.0 114.0 115.0 113.0 09:33:56 INFO - PID 7836 | 09:33:56 INFO - PID 7836 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:148.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 09:33:56 INFO - PID 7836 | Values: 148.0 148.0 149.0 148.0 147.0 148.0 148.0 149.0 147.0 148.0 09:33:56 INFO - PID 7836 | 09:33:56 INFO - PID 7836 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 09:33:56 INFO - PID 7836 | Values: 93.0 93.0 95.0 93.0 94.0 94.0 94.0 94.0 94.0 94.0 09:33:56 INFO - PID 7836 | 09:33:56 INFO - PID 7836 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:0.67 (1.2%) stddev-sans-first:0.60 09:33:56 INFO - PID 7836 | Values: 53.0 54.0 54.0 55.0 54.0 55.0 54.0 53.0 54.0 54.0 09:33:56 INFO - PID 7836 | 09:33:56 INFO - PID 7836 | [#9] json-stringify-tinderbox Cycles:10 Average:38.50 Median:39.00 stddev:0.53 (1.4%) stddev-sans-first:0.53 09:33:56 INFO - PID 7836 | Values: 38.0 39.0 38.0 39.0 39.0 39.0 38.0 39.0 38.0 38.0 09:33:56 INFO - PID 7836 | 09:33:56 INFO - PID 7836 | [#10] stanford-crypto-aes Cycles:10 Average:77.10 Median:77.00 stddev:3.67 (4.8%) stddev-sans-first:3.87 09:33:56 INFO - PID 7836 | Values: 76.0 78.0 74.0 74.0 79.0 86.0 75.0 75.0 75.0 79.0 09:33:56 INFO - PID 7836 | 09:33:56 INFO - PID 7836 | [#11] stanford-crypto-ccm Cycles:10 Average:127.10 Median:123.50 stddev:15.06 (12.2%) stddev-sans-first:15.81 09:33:56 INFO - PID 7836 | Values: 121.0 127.0 142.0 120.0 120.0 115.0 124.0 115.0 123.0 164.0 09:33:56 INFO - PID 7836 | 09:33:56 INFO - PID 7836 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.90 Median:175.50 stddev:4.18 (2.4%) stddev-sans-first:4.41 09:33:56 INFO - PID 7836 | Values: 176.0 170.0 175.0 171.0 174.0 172.0 181.0 182.0 177.0 171.0 09:33:56 INFO - PID 7836 | 09:33:56 INFO - PID 7836 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.00 stddev:2.57 (4.0%) stddev-sans-first:2.71 09:33:56 INFO - PID 7836 | Values: 66.0 65.0 68.0 71.0 64.0 64.0 63.0 65.0 63.0 63.0 09:33:56 INFO - PID 7836 | -------- Summary: end -------- 09:33:56 INFO - PID 7836 | 09:33:57 INFO - PID 7836 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 09:33:57 INFO - TEST-INFO | 7836: exit 0 09:33:57 INFO - TEST-OK | kraken | took 61028ms 09:33:57 INFO - SUITE-END | took 1172s 09:33:57 INFO - Completed test suite (00:19:33) 09:33:57 INFO - javascript benchmark 09:33:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8546.579079580359, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3289.621693505246, 3318.13728430608, 3326.1518312830253, 3342.398368293707, 3360.865565377385, 6366.172397588312, 6397.904031439532, 6406.327335629761, 6421.165248517686, 6480.12518310028, 6410.96794516028, 6543.2148142222995, 6584.045313429549, 6597.967010164943, 6600.141981542392, 5917.615355001922, 5944.197533333009, 6041.939580604182, 6043.848903777419, 6075.939240607604, 5879.2063071485445, 5879.353271140171, 5891.234139561851, 5936.3173235077875, 5982.8803423931495, 5912.2018527498885, 5965.761369545213, 5966.0, 5971.820845374602, 5992.34084250732, 5863.941360586382, 5920.851978700523, 5968.283805943272, 5968.7612495500125, 6001.399860014008, 5715.657060576337, 5828.388019257959, 5831.766729330821, 5913.231279933581, 5936.584439089265, 6379.585326953733, 6411.262704788963, 6411.871762564722, 6431.871362572722, 6464.74141034358, 6856.897146542806, 6862.005009273627, 6866.141732283465, 6873.140857392826, 6896.448284137257, 6758.986152077178, 6981.301869812978, 6982.720691172347, 6986.510944233905, 7010.614416207107, 6390.648514331663, 6396.1045453636525, 6471.4822814175695, 6482.805515834532, 6492.513061520358, 6943.201531823905, 7019.982102595095, 7089.7873063808165, 7092.787216383516, 7104.822379440556, 6790.694418751069, 6792.422644075309, 6876.484263680144, 6887.965560172167, 6892.104026476526, 6921.7231310747, 6964.408025317804, 6970.546914450544, 6993.720251189896, 7001.264867188974, 6871.931280687129, 6951.6871740771785, 6963.547369420971, 6970.302969702989, 6971.093757811452, 6920.30796920304, 6922.896156557656, 6932.480063995221, 6993.195782484978, 7035.050268213726, 5358.839234822962, 5419.105847535191, 5419.485148910847, 5508.91736623951, 5525.723713814293, 4715.434147902273, 4764.857054288377, 4785.162596545661, 4788.04239152164, 4838.854834354975, 5991.700414979321, 6006.0, 6065.8180254592435, 6072.453479186895, 6138.938610613926, 6930.099087118642, 6936.79189624312, 6995.8600827983155, 7038.577685338896, 7045.11936007999, 5938.8218353449465, 6020.488258498077, 6051.697415129226, 6116.0, 6122.051082082285, 5943.524518038546, 5954.583179177416, 5959.821205363846, 5960.910586341209, 5975.37258587842, 6604.438622717027, 6696.06255124283, 6699.497537684693, 6699.631520266432, 6701.731930722814, 6886.380225779704, 6899.517033807585, 6949.68726407323, 6969.477289203432, 6969.616671082937, 6586.341365863375, 6630.801075967729, 6661.666916654051, 6710.765123220664, 6738.966305168443, 6737.764178253738, 6799.456043516408, 6801.319868013159, 6829.965850170717, 6866.279040700654, 6919.584824910521, 6948.444124469929, 6956.756513522002, 6959.338862808025, 6965.895511567432, 6677.866442671217, 6691.665416729047, 6694.531382803157, 6698.363655452724, 6698.5980841149685, 5574.303212098488, 6658.400743932875, 6666.233383160805, 6676.332366763285, 6694.430973367318, 6690.531662783558, 6693.330666933462, 6749.898751518829, 6779.457643388419, 6779.915213565806, 6638.037484564615, 6780.25417204117, 6818.18181818185, 6916.446684265146, 6921.065656136559, 6415.358464153547, 6428.871422571428, 6451.677416129269, 6458.289588145394, 6560.737570497317], "value": 6305.255255103818, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [810.5866008335751, 856.2978357746648, 885.274075258288, 888.2361169394323, 1142.2232881640482, 29471.852640736794, 39091.0, 39622.0, 39650.20699586006, 39691.20617587647, 39602.0, 39807.20385592293, 39882.60117398825, 39893.60106398935, 39940.60059399405, 7433.368163706092, 7654.3111119999185, 7662.770116896474, 7668.194839541824, 7670.0, 7590.3548198403205, 7680.923190768077, 7682.193369696185, 7683.193264707209, 7691.730789422371, 7650.770476885702, 7704.537727736326, 7709.7301594443925, 7710.190430004853, 7711.922880771177, 7662.770116896474, 7694.384449244075, 7703.807404814868, 7718.382529397691, 7722.922770772277, 7574.242575742437, 7639.69441222356, 7640.235976402371, 7641.847163056764, 7645.655945482466, 35790.82104589487, 35874.28251434983, 35937.460938085685, 35946.64053359459, 35961.28077438463, 3459.515667806509, 3488.023353461035, 3491.3715531204375, 3492.2491664292297, 3492.545969024036, 30760.846195769103, 30828.691713082808, 30830.53754193689, 30834.845825770954, 30844.53733194004, 5136.43499215086, 5144.202648589475, 5184.870378240536, 5192.71440070796, 5193.143131383355, 183002.0, 183156.0, 183762.99999999866, 184670.0, 185653.0, 44322.33516497255, 45558.54441455543, 45581.08837823291, 45584.3162352565, 45625.85935351576, 46226.53773462289, 46771.532284676716, 46957.29564056542, 46979.29531057037, 47021.29468057982, 13743.175409475463, 13964.0, 13978.370973306226, 14711.705765884837, 14829.77755333671, 11222.214444988937, 11310.660680179608, 11319.26424782403, 11322.830157547643, 11323.886761132448, 13189.40647670838, 13243.0, 13274.601761947157, 13336.6665833353, 13387.464501419834, 5395.109806881899, 5505.532029777433, 5509.944900550943, 5514.338279406416, 5585.497305242548, 999.9700008999741, 1032.4785983078498, 1038.8441733739849, 1041.734357738787, 1052.2529004406956, 22580.435489112904, 22645.886770566372, 22711.659325110137, 22791.430214244778, 23000.0, 1065.3022270412891, 1101.4767985206963, 1102.922795404314, 1106.1758989552834, 1129.1869853705318, 11677.416129193507, 11852.881471185177, 11904.940475297568, 11938.940305298418, 11979.940100299442, 230829.0, 232842.0, 233010.0, 233361.0, 233630.0, 48635.27047094297, 48807.51192488101, 48991.510084899404, 49044.50955490399, 49098.0, 1398.084254813099, 1430.5279257845095, 1430.6995530938411, 1435.2751860310477, 1447.7973083768281, 21567.784322156578, 21646.94354036364, 21680.24119155822, 21720.456988575414, 22091.447713807283, 34769.478457822646, 34780.47829282512, 34878.0, 34971.0, 45858.54141458543, 213948.0, 215873.0, 216455.0, 216863.0, 217056.0, 33759.83120084482, 34108.658913410545, 34115.0, 34126.658733413344, 34140.65859341375, 39498.2100357997, 39682.40476392799, 40219.798901005306, 40263.59736402598, 40429.19141617092, 30119.849400753734, 30126.0, 30272.84863575668, 30663.84668076645, 30692.232694182825, 29460.852695736383, 29756.256093597833, 29845.70154298516, 30044.699553005066, 30351.848240758656, 34027.48958765571, 34097.48853767146, 34142.82928585341, 34167.31665366629, 34208.82895585506, 29081.563776543793, 29144.125676229745, 29281.707182927898, 29291.267718307212, 29555.113346599635, 22370.0, 22957.737324446647, 23267.650985235574, 23356.299311020695, 23516.412089697893], "value": 16591.7164210152, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [557.8382269141952, 557.8465921871482, 563.2536888622574, 584.2288179602924, 593.3325009364465, 21795.346139615798, 22465.887670561624, 22530.211442599513, 22546.43633909153, 24028.639570406456, 25146.37134071649, 25846.48307033858, 25874.482510349782, 25882.0, 25900.35249118768, 6459.677016149186, 6604.27352991173, 6610.504212184105, 6612.570182938118, 6615.404613584777, 5885.0, 6161.537884658646, 6390.968045159768, 6613.6693165341785, 6616.900746488806, 6593.769218077368, 6607.5374723769355, 6612.900806487906, 6613.6693165341785, 6618.404343609074, 6570.868582628368, 6592.241892182413, 6596.472282217411, 6596.703148358288, 6601.2738598754095, 6136.048912418582, 6147.446729794296, 6153.077038444225, 6157.0456579230295, 6159.784407545759, 28016.579751303747, 28389.290267743265, 28827.135185944455, 29299.853500732574, 29426.558601620993, 2042.5710600773855, 2067.131804642054, 2071.616750901073, 2072.471519762466, 2074.0770357191054, 19262.807371926243, 19889.701654475193, 19895.20419183231, 19903.50241243966, 19907.601847963106, 2386.246921212963, 2413.746556611548, 2462.409021834747, 2489.4523204894895, 2496.151308555082, 128180.0, 133459.0, 133623.0, 133982.0, 134082.0, 14709.41162353494, 15068.397264109313, 15178.924105379623, 15234.847651523565, 15380.38478460849, 22197.445063873532, 23369.88315058448, 23371.182008629617, 23414.882925585604, 23495.647565286534, 9801.11789938909, 9822.557984890555, 10012.69961901144, 10058.949705251427, 10112.039356261143, 8998.910010899937, 9023.233025192802, 9030.27757779376, 9087.591058402388, 9124.771880703036, 9362.689223508714, 9402.576884040234, 9538.0, 9582.31695125366, 9634.90365096354, 2751.7660998815327, 2764.737349951751, 2768.207495899038, 2800.341919648881, 2883.7116288370994, 966.3911735606523, 972.1105188752213, 982.6560703753629, 994.9950250248702, 1008.2186305613209, 19268.940208288677, 19996.700049499268, 20534.281300154424, 23077.307680769267, 24088.15691450791, 1017.2574020964737, 1039.2309690828884, 1058.8623478947688, 1059.6979860739652, 1063.106990128296, 11051.61319353835, 11259.605913792979, 11333.206675532634, 11345.205835591594, 11399.601013964471, 489962.0, 509902.0, 534760.0, 538398.0, 538898.0, 114747.0, 114849.0, 116503.41748291205, 117031.41484292694, 117820.41089794497, 1220.414201183437, 1237.5297386992886, 1242.8446444194476, 1259.389196239823, 1267.9746405071846, 17830.5542361442, 18104.72842907384, 18174.909125454287, 18418.907905460925, 18862.905685471484, 51361.22958155556, 53039.20441193308, 53296.4670353307, 59902.40097598968, 60845.087323689295, 63325.0, 67430.0, 68326.97509537262, 68968.6551567239, 69703.65148174227, 14502.637434064232, 14779.039362441406, 14952.177630230442, 14996.100233985995, 15012.92493537569, 23332.0, 23365.41586460352, 23521.76478235196, 23807.166749163694, 23822.166224182765, 18075.09624518795, 19189.0, 19230.134643940775, 19239.711404329228, 19388.515287117934, 16584.0, 16852.241649125575, 17106.14469276556, 17135.05757183367, 17216.225269862636, 19531.70702439436, 19862.70205946883, 20004.699929500777, 20007.59984800325, 20142.39572812818, 14059.578212653636, 14495.78256326135, 14595.854041459874, 14607.415703372171, 14609.853901461274, 18450.26198952027, 18714.25142994265, 18722.438326850217, 18762.624747505248, 18766.343177988703], "value": 12095.87044259119, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1328.58813767732, 1367.9452821887126, 1382.4746596293407, 1386.5840247925619, 1389.1942673249516, 14857.925710371446, 15504.379824807022, 15591.0, 15599.45401910932, 15605.84394156061, 15480.5355839325, 15755.921220393882, 15783.921080394583, 15836.129012904286, 15837.287322070506, 2358.363241924679, 2757.269323629235, 2829.25024868409, 2830.391465834845, 2832.4051949090717, 2401.4356626192916, 2690.6636670416196, 2822.139247529503, 2828.9292767680868, 2832.4335132973383, 2635.894564217429, 2647.7087520372743, 2661.7471340222646, 2672.131557243894, 2672.4521473097966, 2678.370582913014, 2702.3514356554483, 2706.7022627511055, 2719.8504082275467, 2721.319670082479, 2624.4619852930105, 2646.1532309660915, 2655.2963464681875, 2655.3892604701027, 2656.6546348974703, 15200.771988420183, 15263.465778697804, 15399.615009624737, 15401.845981540153, 15414.614634634112, 7802.180771019018, 8140.063892652362, 8153.1439198884445, 8154.633041513087, 8158.755237342889, 5582.0, 5584.21820945068, 5585.888282234332, 5747.3103227612955, 5880.147378630075, 1971.122850331603, 1972.5956178983274, 1987.3739771971782, 2022.5853699991615, 2026.2604149485478, 27065.45869082627, 27099.1870243893, 27262.182134535997, 27307.86346068277, 27450.313742156406, 6566.343365663395, 6658.9001164982565, 6702.631355275506, 6839.863202736017, 6860.725570977105, 17549.122543872756, 17631.559211019827, 17639.470815875287, 17677.7348339775, 17690.292388304322, 7828.804279893048, 8019.438639295194, 8028.518288902685, 8029.357651387992, 8037.638306276232, 7285.089363829521, 7399.704011839466, 7460.589667568336, 7478.140013898471, 7483.289087536676, 5299.4700529946695, 5415.0, 5452.236686863842, 5474.233607294982, 5502.202180683778, 1561.7501199807975, 1570.57594449498, 1573.087609186669, 1575.94484193055, 1579.155151993678, 853.9231469167805, 874.7200895713438, 889.7953470701646, 891.9018907920124, 893.3657103456619, 7253.5855508176455, 7845.803854903673, 8149.877751833727, 8156.9184308156155, 8248.917510824935, 726.6875243645284, 734.071399679406, 737.7491652837954, 740.4742632730714, 753.6231884057971, 4371.759553224603, 4406.162829062468, 4431.379606855043, 4432.357308190294, 4448.266036104072, 20878.164873404894, 21340.253091141734, 21664.675029874565, 21666.35000949974, 21934.670979935312, 20879.26922557703, 22408.55182896333, 22427.102915883508, 22428.32715018552, 22453.21413750511, 10744.838827417596, 11393.487293071832, 11753.4710938008, 11756.647300580997, 11778.11664125194, 8786.956065219632, 8991.100889910957, 9037.231835294073, 9125.954370228106, 9136.588853501475, 18453.446396608124, 18762.343317983807, 19037.714434283775, 19232.61534769269, 19457.318993835146, 19087.8091219086, 19524.8047519523, 19767.20931162795, 19776.505587359854, 19791.109400076744, 12216.389180541115, 12292.508299667914, 12400.937995310327, 12479.126461147633, 12489.875101248872, 13970.0, 14093.224872632103, 14113.2237726926, 14128.011039227156, 14133.717325653635, 11404.885951140383, 11518.712032199262, 11606.70983225426, 11637.825432618687, 11721.765564688829, 11004.119670426508, 11054.889451105386, 11112.22214444981, 11401.3729244889, 11431.0, 9513.571889265138, 9579.0, 9604.135627793532, 9605.567749451151, 9610.711678649652, 6897.241303456716, 6909.0, 6930.3762661358705, 6941.34057264559, 6987.615681137586, 6956.965215173892, 7006.439484841302, 7035.401990830836, 7054.0, 7058.858822823412], "value": 6673.542487919423, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.89772551523873, 513.9977044762713, 523.1446584833797, 526.4577485190256, 535.9040761642441, 6347.5556711030185, 6367.0, 6384.297727249998, 6389.137466442035, 6401.839954001152, 5987.311459182195, 6155.784547540837, 6202.81391558254, 6272.153259309992, 6274.623522588648, 4921.876953076175, 4967.900641987158, 5017.0, 5020.4226513951, 5065.037642847866, 4878.219484882419, 4938.901221975559, 5006.599472042228, 5040.6471546991725, 5087.287779710843, 4941.50454020719, 4948.134076536592, 5024.924626130611, 5051.419086805027, 5057.190849464087, 4988.0, 4999.725015124167, 5018.999999999982, 5053.343065401493, 5097.439281679012, 4462.263726485126, 4473.46318441789, 4499.302608095718, 4503.0, 4546.431696037996, 6179.62922224668, 6242.6878656067465, 6248.812535623893, 6268.341824108494, 6284.2458904931245, 5780.855478613068, 5785.334686511063, 5796.97101514494, 5819.243498345188, 5823.155642431866, 6495.577787443848, 6589.143411356493, 6660.333966603349, 6711.194656641185, 6713.597184168965, 4914.0, 4918.754062296871, 4921.729304888229, 4929.556339929423, 4945.431275403339, 6936.0, 6979.546329488618, 7012.298770122947, 7024.402925751267, 7092.0, 5001.64988450805, 5026.497350265017, 5112.565431938327, 5120.7951681933055, 5134.87162820925, 5076.7207803571155, 5106.872328191825, 5111.846644600668, 5116.462771409023, 5158.63889527737, 5749.8562535936935, 5750.396208398058, 5756.740946657326, 5757.890739000976, 5777.335606405233, 6680.331966803281, 6701.865962680719, 6725.529212955046, 6737.551426443339, 6760.898586521206, 1649.3320205316627, 1699.566610514311, 1772.503698964292, 1782.5989152440598, 1786.7945186303482, 1192.9403529823649, 1217.6773155113901, 1218.7562487502357, 1235.882591153839, 1247.8065899785547, 5745.770169193269, 5827.096799996008, 5870.647761134345, 5891.793787217427, 5897.823065307962, 6490.5781124227715, 6539.640319782363, 6561.934380656227, 6585.916731626422, 6710.731570737214, 5750.568707346882, 5765.279340082489, 5781.993363450982, 5865.824025279248, 5870.853228669317, 3535.734819888519, 3564.91087722809, 3573.6069032406413, 3596.5504311961004, 3598.6221446747713, 6435.0, 6480.416762491398, 6491.772787952494, 6566.0, 6569.37590928861, 6800.353966373186, 6812.795616131524, 6830.829229269308, 6875.690593923286, 6954.478414118961, 6407.199100112486, 6428.71070801815, 6449.5485316027425, 6552.8361790955605, 6564.538298138198, 6613.6693165340575, 6659.701358235291, 6687.121469547748, 6744.190697116377, 6773.62745049027, 6569.441597464269, 6589.505787065893, 6633.303503132294, 6678.3989440950545, 6708.597484150967, 6400.51996100285, 6408.839779005562, 6498.805035848932, 6499.805005849832, 6519.380658837403, 4952.380952380952, 4992.051510213048, 5021.598272138147, 5022.924656130088, 5039.924401134059, 6674.130124046706, 6742.190937087385, 6752.5948443093575, 6757.966210168917, 6763.391294783493, 6516.869662606627, 6561.639109849004, 6588.8023359299295, 6596.888995521387, 6618.551136079682, 6461.903071454026, 6495.863223935887, 6532.5753826001155, 6564.573302735307, 6569.835754106186, 5889.499392551681, 6407.238009547412, 6427.710753016219, 6517.478601711757, 6517.902231466626, 6569.868602628017, 6607.339266073354, 6617.338266173537, 6658.400743933069, 6670.633115178712, 6277.309495955533, 6279.96860015697, 6298.433141017331, 6349.682515874096, 6440.967795160994], "value": 5082.682676965205, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [891.9955400222998, 908.9590968406421, 919.5264438814007, 921.0329154387891, 939.2204470289659, 16584.0, 17020.0, 17106.230219640114, 17146.142692865353, 21077.15691372347, 19261.325853595128, 21078.15687372503, 21661.783382166213, 21675.241366552174, 21676.674849877265, 905.7282815155477, 916.8533034714447, 922.7001224601997, 924.3945215883607, 924.875141855851, 928.2759447630858, 932.6036434515338, 934.1639232886563, 934.887813462382, 935.550935550936, 986.57577241786, 986.9506524673774, 987.3483500889414, 989.8911119776819, 991.6876184002052, 977.6969139566758, 979.9363041402322, 980.6959842448828, 980.9068138526828, 982.6315131825565, 726.9745558905465, 729.0267492896988, 729.3071581997071, 729.8102493351714, 730.1785491322263, 18498.2600695972, 18898.622027559508, 19188.328408505775, 19720.21119155232, 19738.0, 21179.152833886626, 21445.142194312208, 21670.566588668138, 21691.566168676694, 21908.780912190992, 16118.75821862673, 16662.083585402797, 16690.58273543159, 16922.238499267438, 17646.558836029202, 3850.2299540092095, 3883.10688541637, 3886.514185726784, 3893.3186692329014, 3894.1432884765304, 123786.0, 123980.38009809983, 125088.37455812663, 127410.99999999907, 128786.0, 15441.382344706311, 15559.22203889801, 15721.0, 15922.203889805463, 15995.520134395987, 30151.396972060873, 30178.396432071237, 30299.394012119636, 30880.69119308823, 30940.535891961637, 7497.1753107158165, 7834.373250139975, 8491.490510569385, 8576.699815506427, 8592.0, 4186.748795072305, 4243.172581346669, 4249.936250956238, 4251.744895306291, 4272.978635106804, 5188.11801993662, 5230.476952304739, 5247.239150323182, 5267.762950667229, 5280.577553795687, 1035.8601588785568, 1056.3661802918189, 1060.6181774561153, 1063.8829728729834, 1067.3275836223129, 680.1396233764292, 690.0925283252507, 692.2765709833236, 697.6616341074575, 703.4372501998381, 11298.435078246055, 11674.182807203415, 11687.824682629767, 11787.823182652268, 12242.510299588095, 651.3974573519475, 657.4641667041346, 667.2726727866649, 668.8227619680788, 673.9856515943571, 5740.626859254218, 5990.7903223386975, 6011.308699499614, 6496.675166241669, 6612.305707900698, 109776.4511177455, 111542.44228778966, 112661.0, 112706.0, 113231.4338428319, 100924.0, 103605.0, 103716.48141759394, 103847.48076259722, 104375.0, 84613.0, 84632.57683711665, 86890.56554717313, 88213.0, 88550.55724721335, 11884.465199066235, 11943.522259109539, 12057.75884482288, 12171.573994910135, 12181.75636487283, 41352.37971430371, 41396.586034139276, 41723.0, 41996.580034199265, 42551.7872410636, 53808.0, 53975.0, 54117.0, 54248.728756355966, 56033.0, 18291.26834926588, 18622.627547449247, 18644.720329194803, 18678.25286988505, 18767.624647506702, 20868.0, 20940.05769740335, 20960.790392095885, 21101.366958991253, 21169.25907593227, 18949.43151705451, 19036.619267614846, 19195.520111997314, 19205.039748012263, 19280.0, 19277.421677349703, 19618.215271388985, 19623.215071397557, 19696.21215151435, 19712.21151153938, 17332.480025599252, 17588.73616895773, 17609.29562817524, 17652.73520897213, 17662.381816636356, 12677.429515671633, 13041.152325098838, 13215.867841321464, 13447.193168409927, 13908.374123164684, 14690.485832995793, 14983.775243371576, 15129.778526601338, 15141.394344226108, 15145.318460669076], "value": 9079.228193206842, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1489.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 107.0, 99.0, 107.0, 106.0, 116.0, 108.0, 98.0, 106.0, 109.0], "value": 105.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 115.0, 140.0, 167.0, 115.0, 146.0, 142.0, 118.0, 190.0, 120.0], "value": 140.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 162.0, 163.0, 164.0, 162.0, 162.0, 164.0, 174.0, 170.0], "value": 164.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 80.0, 73.0, 179.0, 74.0, 75.0, 74.0, 73.0, 152.0, 75.0], "value": 92.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 138.0, 79.0, 80.0, 80.0, 123.0, 79.0, 80.0, 121.0], "value": 93.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 114.0, 113.0, 114.0, 114.0, 114.0, 115.0, 113.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 149.0, 148.0, 147.0, 148.0, 148.0, 149.0, 147.0, 148.0], "value": 148.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 95.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 54.0, 55.0, 54.0, 55.0, 54.0, 53.0, 54.0, 54.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 39.0, 38.0, 39.0, 39.0, 39.0, 38.0, 39.0, 38.0, 38.0], "value": 38.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 74.0, 74.0, 79.0, 86.0, 75.0, 75.0, 75.0, 79.0], "value": 77.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 127.0, 142.0, 120.0, 120.0, 115.0, 124.0, 115.0, 123.0, 164.0], "value": 127.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 170.0, 175.0, 171.0, 174.0, 172.0, 181.0, 182.0, 177.0, 171.0], "value": 174.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 68.0, 71.0, 64.0, 64.0, 63.0, 65.0, 63.0, 63.0], "value": 65.2, "unit": "score"}]}]} 09:33:57 INFO - Return code: 0 09:33:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:33:58 INFO - # TBPL SUCCESS # 09:33:58 INFO - Running post-action listener: _package_coverage_data 09:33:58 INFO - Running post-action listener: _resource_record_post_action 09:33:58 INFO - [mozharness: 2017-09-01 16:33:58.060199Z] Finished run-tests step (success) 09:33:58 INFO - Running post-run listener: _resource_record_post_run 09:33:58 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 49152; Write bytes: 403296256; Read time: 304; Write time: 252352 09:33:58 INFO - TinderboxPrint: CPU usage
13.1% 09:33:58 INFO - TinderboxPrint: I/O read bytes / time
49,152 / 304 09:33:58 INFO - TinderboxPrint: I/O write bytes / time
403,296,256 / 252,352 09:33:58 INFO - TinderboxPrint: CPU idle
8,244.3 (86.9%) 09:33:58 INFO - TinderboxPrint: CPU user
1,193.9 (12.6%) 09:33:58 INFO - TinderboxPrint: Swap in / out
0 / 0 09:33:58 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 109531136; Read time: 68; Write time: 85520 09: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 09:33:58 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 45056; Write bytes: 286285824; Read time: 236; Write time: 154152 09:33:58 INFO - Running post-run listener: _upload_blobber_files 09:33:58 INFO - Blob upload gear active. 09:33:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:33:58 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:33:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:33:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:33:59 INFO - (blobuploader) - INFO - Open directory for files ... 09:33:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:33:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:34:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:34:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:34:00 INFO - (blobuploader) - INFO - Done attempting. 09:34:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:34:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:34:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:34:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:34:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:34:01 INFO - (blobuploader) - INFO - Done attempting. 09:34:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:34:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:34:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:34:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:34:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:34:02 INFO - (blobuploader) - INFO - Done attempting. 09:34:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:34:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:34:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:34:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:34:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:34:05 INFO - (blobuploader) - INFO - Done attempting. 09:34:05 INFO - (blobuploader) - INFO - Iteration through files over. 09:34:05 INFO - Return code: 0 09:34:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:34:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:34:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dddbd9fe8842a54246aff3041327cd71f795dbeb3b07db35f00d00d680a3b07852d9c580b57dce580451a04e11fc5936c1419e395d0a55235d68d8ebed64b871", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95fd43f390b537a67f3a6f72c0d051d1cef48599381129c9b94a75971542f922e7ad7be421549e1acaa15f517f4c44b6c7d79eaafc08b8f42bb329bde985f967", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f046e0c0f487ea9ba7d028bfe3cee1b1336530ae38fe04f02d4e156d1c74e60b5b9288d23d115c33de0a553681a57b65570fab87498ed05929adeeb1ce2b2b12", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fe782497331aa497ddba6e7f86bd6e478f0c921883b56c91dcbf90ac6b727a7a376d11d18f70ba7dfb7ac430f9c27fd626d2d1ef750b6a64342ed4cd191ef0b"} 09:34:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:34:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:34:05 INFO - Contents: 09:34:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dddbd9fe8842a54246aff3041327cd71f795dbeb3b07db35f00d00d680a3b07852d9c580b57dce580451a04e11fc5936c1419e395d0a55235d68d8ebed64b871", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95fd43f390b537a67f3a6f72c0d051d1cef48599381129c9b94a75971542f922e7ad7be421549e1acaa15f517f4c44b6c7d79eaafc08b8f42bb329bde985f967", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f046e0c0f487ea9ba7d028bfe3cee1b1336530ae38fe04f02d4e156d1c74e60b5b9288d23d115c33de0a553681a57b65570fab87498ed05929adeeb1ce2b2b12", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fe782497331aa497ddba6e7f86bd6e478f0c921883b56c91dcbf90ac6b727a7a376d11d18f70ba7dfb7ac430f9c27fd626d2d1ef750b6a64342ed4cd191ef0b"} 09:34:05 INFO - Running post-run listener: copy_logs_to_upload_dir 09:34:05 INFO - Copying logs to upload dir... 09:34:05 INFO - mkdir: /builds/slave/test/build/upload/logs 09:34:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.995787 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-01 09:34:05.379155) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 09:34:05.382861) ========= 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=db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dddbd9fe8842a54246aff3041327cd71f795dbeb3b07db35f00d00d680a3b07852d9c580b57dce580451a04e11fc5936c1419e395d0a55235d68d8ebed64b871", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95fd43f390b537a67f3a6f72c0d051d1cef48599381129c9b94a75971542f922e7ad7be421549e1acaa15f517f4c44b6c7d79eaafc08b8f42bb329bde985f967", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f046e0c0f487ea9ba7d028bfe3cee1b1336530ae38fe04f02d4e156d1c74e60b5b9288d23d115c33de0a553681a57b65570fab87498ed05929adeeb1ce2b2b12", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fe782497331aa497ddba6e7f86bd6e478f0c921883b56c91dcbf90ac6b727a7a376d11d18f70ba7dfb7ac430f9c27fd626d2d1ef750b6a64342ed4cd191ef0b"} build_url:https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011260 build_url: 'https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dddbd9fe8842a54246aff3041327cd71f795dbeb3b07db35f00d00d680a3b07852d9c580b57dce580451a04e11fc5936c1419e395d0a55235d68d8ebed64b871", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95fd43f390b537a67f3a6f72c0d051d1cef48599381129c9b94a75971542f922e7ad7be421549e1acaa15f517f4c44b6c7d79eaafc08b8f42bb329bde985f967", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f046e0c0f487ea9ba7d028bfe3cee1b1336530ae38fe04f02d4e156d1c74e60b5b9288d23d115c33de0a553681a57b65570fab87498ed05929adeeb1ce2b2b12", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fe782497331aa497ddba6e7f86bd6e478f0c921883b56c91dcbf90ac6b727a7a376d11d18f70ba7dfb7ac430f9c27fd626d2d1ef750b6a64342ed4cd191ef0b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 09:34:05.409651) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:34:05.410000) ========= 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=db7e7c15f3ca076d53731445000001ba-1504282396.548857-29339869 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004487 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:34:05.495527) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 09:34:05.495841) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 09:34:05.496196) ========= ========= Total master_lag: 0.19 =========