builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-058 starttime: 1504282549.93 results: success (0) revision: 34933f6390d52779ea498a6a5fd5f34d54734780 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 09:15:49.930564) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 09:15:49.931167) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 09:15:49.931452) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 09:15:49.947211) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:15:49.947487) ========= 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=0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-01 09:15:49-- 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% 40.8M=0s 2017-09-01 09:15:50 (40.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.116040 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:15:50.078450) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:15:50.078779) ========= 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=0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022203 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:15:50.128239) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:15:50.128576) ========= 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=0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-01 09:15:50,180 truncating revision to first 12 chars 2017-09-01 09:15:50,181 Setting DEBUG logging. 2017-09-01 09:15:50,181 attempt 1/10 2017-09-01 09:15:50,181 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:15:50,576 unpacking tar archive at: mozilla-central-34933f6390d5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.722602 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:15:50.865801) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 09:15:50.866120) ========= script_repo_revision: 34933f6390d52779ea498a6a5fd5f34d54734780 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 09:15:50.866490) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 09:15:50.866750) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 09:15:50.878535) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-09-01 09:15:50.878862) ========= /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=0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:15:51 INFO - MultiFileLogger online at 20170901 09:15:51 in /builds/slave/test 09:15:51 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:15:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:15:51 INFO - {'append_to_log': False, 09:15:51 INFO - 'base_work_dir': '/builds/slave/test', 09:15:51 INFO - 'blob_upload_branch': 'Firefox', 09:15:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:15:51 INFO - 'branch': 'Firefox', 09:15:51 INFO - 'buildbot_json_path': 'buildprops.json', 09:15:51 INFO - 'code_coverage': False, 09:15:51 INFO - 'config_files': ('talos/linux_config.py',), 09:15:51 INFO - 'default_actions': ('clobber', 09:15:51 INFO - 'read-buildbot-config', 09:15:51 INFO - 'download-and-extract', 09:15:51 INFO - 'populate-webroot', 09:15:51 INFO - 'create-virtualenv', 09:15:51 INFO - 'install', 09:15:51 INFO - 'setup-mitmproxy', 09:15:51 INFO - 'run-tests'), 09:15:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:15:51 INFO - 'disable_ccov_upload': False, 09:15:51 INFO - 'disable_stylo': False, 09:15:51 INFO - 'download_minidump_stackwalk': True, 09:15:51 INFO - 'download_symbols': 'ondemand', 09:15:51 INFO - 'enable_stylo': False, 09:15:51 INFO - 'enable_webrender': False, 09:15:51 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 09:15:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:15:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:15:51 INFO - 'gecko_profile': False, 09:15:51 INFO - 'gecko_profile_interval': 0, 09:15:51 INFO - 'installer_path': 'installer.exe', 09:15:51 INFO - 'log_level': 'info', 09:15:51 INFO - 'log_name': 'talos', 09:15:51 INFO - 'log_to_console': True, 09:15:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:15:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:15:51 INFO - 'opt_config_files': (), 09:15:51 INFO - 'pip_index': False, 09:15:51 INFO - 'suite': 'dromaeojs-e10s', 09:15:51 INFO - 'system_bits': '32', 09:15:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:15:51 INFO - 'title': 'talos-linux64-ix-058', 09:15:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:15:51 INFO - 'use_talos_json': True, 09:15:51 INFO - 'verify': 'False', 09:15:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:15:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:15:51 INFO - 'work_dir': 'build'} 09:15:51 INFO - [mozharness: 2017-09-01 16:15:51.068542Z] Running clobber step. 09:15:51 INFO - Running pre-action listener: _resource_record_pre_action 09:15:51 INFO - Running main action method: clobber 09:15:51 INFO - rmtree: /builds/slave/test/build 09:15:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:15:52 INFO - Running post-action listener: _resource_record_post_action 09:15:52 INFO - [mozharness: 2017-09-01 16:15:52.096622Z] Finished clobber step (success) 09:15:52 INFO - [mozharness: 2017-09-01 16:15:52.096720Z] Running read-buildbot-config step. 09:15:52 INFO - Running pre-action listener: _resource_record_pre_action 09:15:52 INFO - Running main action method: read_buildbot_config 09:15:52 INFO - Using buildbot properties: 09:15:52 INFO - { 09:15:52 INFO - "project": "", 09:15:52 INFO - "product": "firefox", 09:15:52 INFO - "who": "archaeopteryx@coole-files.de", 09:15:52 INFO - "installer_path": "public/build/target.tar.bz2", 09:15:52 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 09:15:52 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 09:15:52 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 09:15:52 INFO - "stage_platform": "linux64-qr", 09:15:52 INFO - "basedir": "/builds/slave/test", 09:15:52 INFO - "buildnumber": 25, 09:15:52 INFO - "platform": "ubuntu64_hw_qr", 09:15:52 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:15:52 INFO - "slavebuilddir": "test", 09:15:52 INFO - "taskId": "cunfMAz3QsCcBt78qXSCzw", 09:15:52 INFO - "branch": "mozilla-central", 09:15:52 INFO - "script_repo_revision": "production", 09:15:52 INFO - "revision": "34933f6390d52779ea498a6a5fd5f34d54734780", 09:15:52 INFO - "slavename": "talos-linux64-ix-058", 09:15:52 INFO - "repo_path": "mozilla-central" 09:15:52 INFO - } 09:15:52 INFO - Finding installer, test and symbols from parent task. 09:15:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cunfMAz3QsCcBt78qXSCzw'}, attempt #1 09:15:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cunfMAz3QsCcBt78qXSCzw'}, attempt #1 09:15:53 INFO - Task dependencies: HW9QGOoZRpaQ0PfZ0HF4ew 09:15:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew'}, attempt #1 09:15:53 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.tar.bz2 09:15:53 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.test_packages.json 09:15:53 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.crashreporter-symbols.zip 09:15:53 INFO - Running post-action listener: _resource_record_post_action 09:15:53 INFO - [mozharness: 2017-09-01 16:15:53.542091Z] Finished read-buildbot-config step (success) 09:15:53 INFO - [mozharness: 2017-09-01 16:15:53.542311Z] Running download-and-extract step. 09:15:53 INFO - Running pre-action listener: _resource_record_pre_action 09:15:53 INFO - Running main action method: download_and_extract 09:15:53 INFO - mkdir: /builds/slave/test/build/tests 09:15:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:53 INFO - trying https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.test_packages.json 09:15:53 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:15:53 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:15:54 INFO - Downloaded 989 bytes. 09:15:54 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:15:54 INFO - Using the following test package requirements: 09:15:54 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:15:54 INFO - u'common': [u'target.common.tests.zip'], 09:15:54 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:15:54 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:15:54 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:15:54 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:15:54 INFO - u'mozbase': [u'target.common.tests.zip'], 09:15:54 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:15:54 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:15:54 INFO - u'web-platform': [u'target.common.tests.zip', 09:15:54 INFO - u'target.web-platform.tests.tar.gz'], 09:15:54 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:15:54 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:15:54 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:15:54 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:15:54 INFO - Fetch https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.common.tests.zip into memory 09:15:55 INFO - Content-Length response header: 54957582 09:15:55 INFO - Bytes received: 54957582 09:16:06 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:16:06 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:16:06 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:16:06 INFO - Fetch https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.common.tests.zip into memory 09:16:07 INFO - Content-Length response header: 54957582 09:16:07 INFO - Bytes received: 54957582 09:16:19 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:16:19 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:16:19 INFO - Fetch https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.talos.tests.zip into memory 09:16:20 INFO - Content-Length response header: 14496650 09:16:20 INFO - Bytes received: 14496650 09:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:20 INFO - trying https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.tar.bz2 09:16:20 INFO - Downloading https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:16:20 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:16:21 INFO - Downloaded 62495046 bytes. 09:16:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.tar.bz2 09:16:21 INFO - mkdir: /builds/slave/test/properties 09:16:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:16:21 INFO - Writing to file /builds/slave/test/properties/build_url 09:16:21 INFO - Contents: 09:16:21 INFO - build_url:https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.tar.bz2 09:16:21 INFO - Running post-action listener: _resource_record_post_action 09:16:21 INFO - Running post-action listener: find_tests_for_verification 09:16:21 INFO - Running post-action listener: set_extra_try_arguments 09:16:21 INFO - [mozharness: 2017-09-01 16:16:21.379333Z] Finished download-and-extract step (success) 09:16:21 INFO - [mozharness: 2017-09-01 16:16:21.379430Z] Running populate-webroot step. 09:16:21 INFO - Running pre-action listener: _resource_record_pre_action 09:16:21 INFO - Running main action method: populate_webroot 09:16:21 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:16:21 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:16:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:16:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:16:21 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:16:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:16:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:16:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:21 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 09:16:21 INFO - 'talos_options': ['--enable-stylo'], 09:16:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:16:21 INFO - 'tests': ['damp', 'tps']}, 09:16:21 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 09:16:21 INFO - 'talos_options': ['--enable-stylo'], 09:16:21 INFO - 'tests': ['damp', 'tps']}, 09:16:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:16:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:16:21 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:16:21 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:16:21 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:16:21 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:16:21 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 09:16:21 INFO - 'talos_options': ['--enable-stylo'], 09:16:21 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:16:21 INFO - 'other-e10s': {'tests': ['a11yr', 09:16:21 INFO - 'ts_paint', 09:16:21 INFO - 'tpaint', 09:16:21 INFO - 'sessionrestore', 09:16:21 INFO - 'sessionrestore_many_windows', 09:16:21 INFO - 'sessionrestore_no_auto_restore', 09:16:21 INFO - 'tabpaint']}, 09:16:21 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:16:21 INFO - 'tests': ['a11yr', 09:16:21 INFO - 'ts_paint', 09:16:21 INFO - 'tpaint', 09:16:21 INFO - 'sessionrestore', 09:16:21 INFO - 'sessionrestore_many_windows', 09:16:21 INFO - 'sessionrestore_no_auto_restore', 09:16:21 INFO - 'tabpaint']}, 09:16:21 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 09:16:21 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 09:16:21 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:16:21 INFO - 'tests': ['bloom_basic_singleton']}, 09:16:21 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:16:21 INFO - 'tests': ['bloom_basic']}, 09:16:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:16:21 INFO - 'tsvgr_opacity', 09:16:21 INFO - 'tart', 09:16:21 INFO - 'tscrollx', 09:16:21 INFO - 'tsvg_static']}, 09:16:21 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:16:21 INFO - 'tests': ['tsvgx', 09:16:21 INFO - 'tsvgr_opacity', 09:16:21 INFO - 'tart', 09:16:21 INFO - 'tscrollx', 09:16:21 INFO - 'tsvg_static']}, 09:16:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:16:21 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 09:16:21 INFO - 'talos_options': ['--enable-stylo'], 09:16:21 INFO - 'tests': ['tp5o']}, 09:16:21 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:16:21 INFO - 'talos_options': ['--mitmproxy', 09:16:21 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:16:21 INFO - '--firstNonBlankPaint'], 09:16:21 INFO - 'tests': ['tp6_google', 09:16:21 INFO - 'tp6_youtube', 09:16:21 INFO - 'tp6_amazon', 09:16:21 INFO - 'tp6_facebook']}, 09:16:21 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:16:21 INFO - 'talos_options': ['--enable-stylo', 09:16:21 INFO - '--mitmproxy', 09:16:21 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:16:21 INFO - '--firstNonBlankPaint'], 09:16:21 INFO - 'tests': ['tp6_google', 09:16:21 INFO - 'tp6_youtube', 09:16:21 INFO - 'tp6_amazon', 09:16:21 INFO - 'tp6_facebook']}, 09:16:21 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:16:21 INFO - 'talos_options': ['--enable-stylo', 09:16:21 INFO - '--stylo-threads=1', 09:16:21 INFO - '--mitmproxy', 09:16:21 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:16:21 INFO - '--firstNonBlankPaint'], 09:16:21 INFO - 'tests': ['tp6_google', 09:16:21 INFO - 'tp6_youtube', 09:16:21 INFO - 'tp6_amazon', 09:16:21 INFO - 'tp6_facebook']}, 09:16:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:16:21 INFO - 'talos_options': ['--xperf_path', 09:16:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:21 INFO - 'tests': ['tp5n']}, 09:16:21 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 09:16:21 INFO - 'talos_options': ['--enable-stylo', 09:16:21 INFO - '--xperf_path', 09:16:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:21 INFO - 'tests': ['tp5n']}}} 09:16:21 INFO - Running post-action listener: _resource_record_post_action 09:16:21 INFO - [mozharness: 2017-09-01 16:16:21.393956Z] Finished populate-webroot step (success) 09:16:21 INFO - [mozharness: 2017-09-01 16:16:21.394047Z] Running create-virtualenv step. 09:16:21 INFO - Running pre-action listener: _resource_record_pre_action 09:16:21 INFO - Running main action method: create_virtualenv 09:16:21 INFO - Creating virtualenv /builds/slave/test/build/venv 09:16:21 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:16:21 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 09:16:21 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:16:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:16:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:16:21 INFO - Installing distribute.............................................................................................................................................................................................done. 09:16:22 INFO - Installing pip...............done. 09:16:22 INFO - Return code: 0 09:16:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:16:22 INFO - Reading from file tmpfile_stdout 09:16:22 INFO - Output received: 09:16:22 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:16:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ba0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba4090>, '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 0x2ba4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b9ef90>, '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': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', '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:16:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:16:22 INFO - Using env: {'DISPLAY': ':0', 09:16:22 INFO - 'HOME': '/home/cltbld', 09:16:22 INFO - 'LANG': 'en_US.UTF-8', 09:16:22 INFO - 'LANGUAGE': 'en_US:en', 09:16:22 INFO - 'LOGNAME': 'cltbld', 09:16:22 INFO - 'MAIL': '/var/mail/cltbld', 09:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:22 INFO - 'MOZ_NO_REMOTE': '1', 09:16:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:22 INFO - 'NO_EM_RESTART': '1', 09:16:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:22 INFO - 'PWD': '/builds/slave/test', 09:16:22 INFO - 'SHELL': '/bin/bash', 09:16:22 INFO - 'SHLVL': '1', 09:16:22 INFO - 'TERM': 'linux', 09:16:22 INFO - 'TMOUT': '86400', 09:16:22 INFO - 'USER': 'cltbld', 09:16:22 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', 09:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:22 INFO - '_': '/tools/buildbot/bin/python'} 09:16:23 INFO - Ignoring indexes: http://pypi.python.org/simple/ 09:16:23 INFO - Downloading/unpacking pip>=1.5 09:16:23 INFO - Running setup.py egg_info for package pip 09:16:23 INFO - warning: no files found matching 'pip/cacert.pem' 09:16:23 INFO - warning: no files found matching '*.html' under directory 'docs' 09:16:23 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 09:16:23 INFO - no previously-included directories found matching 'docs/_build/_sources' 09:16:23 INFO - Installing collected packages: pip 09:16:23 INFO - Found existing installation: pip 1.1 09:16:23 INFO - Uninstalling pip: 09:16:23 INFO - Successfully uninstalled pip 09:16:23 INFO - Running setup.py install for pip 09:16:23 INFO - warning: no files found matching 'pip/cacert.pem' 09:16:23 INFO - warning: no files found matching '*.html' under directory 'docs' 09:16:23 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 09:16:23 INFO - no previously-included directories found matching 'docs/_build/_sources' 09:16:23 INFO - Installing pip script to /builds/slave/test/build/venv/bin 09:16:23 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 09:16:23 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 09:16:23 INFO - Successfully installed pip 09:16:23 INFO - Cleaning up... 09:16:23 INFO - Return code: 0 09:16:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:16:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:23 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 0x28341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ba0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba4090>, '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 0x2ba4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b9ef90>, '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': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', '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:16:23 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:16:23 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:16:23 INFO - Using env: {'DISPLAY': ':0', 09:16:23 INFO - 'HOME': '/home/cltbld', 09:16:23 INFO - 'LANG': 'en_US.UTF-8', 09:16:23 INFO - 'LANGUAGE': 'en_US:en', 09:16:23 INFO - 'LOGNAME': 'cltbld', 09:16:23 INFO - 'MAIL': '/var/mail/cltbld', 09:16:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:23 INFO - 'MOZ_NO_REMOTE': '1', 09:16:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:23 INFO - 'NO_EM_RESTART': '1', 09:16:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:23 INFO - 'PWD': '/builds/slave/test', 09:16:23 INFO - 'SHELL': '/bin/bash', 09:16:23 INFO - 'SHLVL': '1', 09:16:23 INFO - 'TERM': 'linux', 09:16:23 INFO - 'TMOUT': '86400', 09:16:23 INFO - 'USER': 'cltbld', 09:16:23 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', 09:16:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:23 INFO - '_': '/tools/buildbot/bin/python'} 09:16:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:23 INFO - Downloading/unpacking psutil>=3.1.1 09:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:16:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:25 INFO - Installing collected packages: psutil 09:16:25 INFO - Running setup.py install for psutil 09:16:25 INFO - building 'psutil._psutil_linux' extension 09:16:25 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:16:25 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:16:25 INFO - building 'psutil._psutil_posix' extension 09:16:25 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:16:25 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:16:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:25 INFO - Successfully installed psutil 09:16:25 INFO - Cleaning up... 09:16:25 INFO - Return code: 0 09:16:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:16:25 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:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:25 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:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:25 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 0x28341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ba0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba4090>, '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 0x2ba4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b9ef90>, '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': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', '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:16:25 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:16:25 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:16:25 INFO - Using env: {'DISPLAY': ':0', 09:16:25 INFO - 'HOME': '/home/cltbld', 09:16:25 INFO - 'LANG': 'en_US.UTF-8', 09:16:25 INFO - 'LANGUAGE': 'en_US:en', 09:16:25 INFO - 'LOGNAME': 'cltbld', 09:16:25 INFO - 'MAIL': '/var/mail/cltbld', 09:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:25 INFO - 'MOZ_NO_REMOTE': '1', 09:16:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:25 INFO - 'NO_EM_RESTART': '1', 09:16:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:25 INFO - 'PWD': '/builds/slave/test', 09:16:25 INFO - 'SHELL': '/bin/bash', 09:16:25 INFO - 'SHLVL': '1', 09:16:25 INFO - 'TERM': 'linux', 09:16:25 INFO - 'TMOUT': '86400', 09:16:25 INFO - 'USER': 'cltbld', 09:16:25 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', 09:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:25 INFO - '_': '/tools/buildbot/bin/python'} 09:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:16:25 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:16:25 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:16:25 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:16:25 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:16:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:16:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:16:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:16:26 INFO - Installing collected packages: mozsystemmonitor 09:16:26 INFO - Running setup.py install for mozsystemmonitor 09:16:27 INFO - Successfully installed mozsystemmonitor 09:16:27 INFO - Cleaning up... 09:16:27 INFO - Return code: 0 09:16:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:16:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:27 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 0x28341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ba0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba4090>, '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 0x2ba4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b9ef90>, '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': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', '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:16:27 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:16:27 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:16:27 INFO - Using env: {'DISPLAY': ':0', 09:16:27 INFO - 'HOME': '/home/cltbld', 09:16:27 INFO - 'LANG': 'en_US.UTF-8', 09:16:27 INFO - 'LANGUAGE': 'en_US:en', 09:16:27 INFO - 'LOGNAME': 'cltbld', 09:16:27 INFO - 'MAIL': '/var/mail/cltbld', 09:16:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:27 INFO - 'MOZ_NO_REMOTE': '1', 09:16:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:27 INFO - 'NO_EM_RESTART': '1', 09:16:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:27 INFO - 'PWD': '/builds/slave/test', 09:16:27 INFO - 'SHELL': '/bin/bash', 09:16:27 INFO - 'SHLVL': '1', 09:16:27 INFO - 'TERM': 'linux', 09:16:27 INFO - 'TMOUT': '86400', 09:16:27 INFO - 'USER': 'cltbld', 09:16:27 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', 09:16:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:27 INFO - '_': '/tools/buildbot/bin/python'} 09:16:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:27 INFO - Downloading/unpacking jsonschema==2.5.1 09:16:27 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:16:27 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:16:27 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:16:27 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:16:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:16:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:16:28 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:16:28 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:16:28 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:16:28 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:16:28 INFO - Downloading functools32-3.2.3-2.tar.gz 09:16:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:16:28 INFO - warning: no files found matching '*.txt' 09:16:28 INFO - no previously-included directories found matching 'build' 09:16:28 INFO - no previously-included directories found matching 'dist' 09:16:28 INFO - no previously-included directories found matching '.git*' 09:16:28 INFO - Installing collected packages: jsonschema, functools32 09:16:28 INFO - Running setup.py install for functools32 09:16:28 INFO - warning: no files found matching '*.txt' 09:16:28 INFO - no previously-included directories found matching 'build' 09:16:28 INFO - no previously-included directories found matching 'dist' 09:16:28 INFO - no previously-included directories found matching '.git*' 09:16:28 INFO - Successfully installed jsonschema functools32 09:16:28 INFO - Cleaning up... 09:16:28 INFO - Return code: 0 09:16:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:16:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x28341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ba0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba4090>, '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 0x2ba4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b9ef90>, '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': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', '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:16:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:16:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:16:28 INFO - Using env: {'DISPLAY': ':0', 09:16:28 INFO - 'HOME': '/home/cltbld', 09:16:28 INFO - 'LANG': 'en_US.UTF-8', 09:16:28 INFO - 'LANGUAGE': 'en_US:en', 09:16:28 INFO - 'LOGNAME': 'cltbld', 09:16:28 INFO - 'MAIL': '/var/mail/cltbld', 09:16:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:28 INFO - 'MOZ_NO_REMOTE': '1', 09:16:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:28 INFO - 'NO_EM_RESTART': '1', 09:16:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:28 INFO - 'PWD': '/builds/slave/test', 09:16:28 INFO - 'SHELL': '/bin/bash', 09:16:28 INFO - 'SHLVL': '1', 09:16:28 INFO - 'TERM': 'linux', 09:16:28 INFO - 'TMOUT': '86400', 09:16:28 INFO - 'USER': 'cltbld', 09:16:28 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', 09:16:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:28 INFO - '_': '/tools/buildbot/bin/python'} 09:16:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:16:28 INFO - Cleaning up... 09:16:28 INFO - Return code: 0 09:16:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:16:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x28341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ba0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba4090>, '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 0x2ba4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b9ef90>, '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': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', '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:16:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:16:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:16:28 INFO - Using env: {'DISPLAY': ':0', 09:16:28 INFO - 'HOME': '/home/cltbld', 09:16:28 INFO - 'LANG': 'en_US.UTF-8', 09:16:28 INFO - 'LANGUAGE': 'en_US:en', 09:16:28 INFO - 'LOGNAME': 'cltbld', 09:16:28 INFO - 'MAIL': '/var/mail/cltbld', 09:16:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:28 INFO - 'MOZ_NO_REMOTE': '1', 09:16:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:28 INFO - 'NO_EM_RESTART': '1', 09:16:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:28 INFO - 'PWD': '/builds/slave/test', 09:16:28 INFO - 'SHELL': '/bin/bash', 09:16:28 INFO - 'SHLVL': '1', 09:16:28 INFO - 'TERM': 'linux', 09:16:28 INFO - 'TMOUT': '86400', 09:16:28 INFO - 'USER': 'cltbld', 09:16:28 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', 09:16:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:28 INFO - '_': '/tools/buildbot/bin/python'} 09:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:29 INFO - Downloading/unpacking blobuploader==1.2.4 09:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:30 INFO - Downloading blobuploader-1.2.4.tar.gz 09:16:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:16:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:16:30 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:16:30 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:16:30 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:16:30 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:16:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:16:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:16:30 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:16:30 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:16:30 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:16:30 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:16:30 INFO - Downloading docopt-0.6.1.tar.gz 09:16:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:16:30 INFO - Installing collected packages: blobuploader, requests, docopt 09:16:30 INFO - Running setup.py install for blobuploader 09:16:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:16:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:16:30 INFO - Running setup.py install for requests 09:16:31 INFO - Running setup.py install for docopt 09:16:31 INFO - Successfully installed blobuploader requests docopt 09:16:31 INFO - Cleaning up... 09:16:31 INFO - Return code: 0 09:16:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:31 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 0x28341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ba0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba4090>, '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 0x2ba4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b9ef90>, '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': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', '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:16:31 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:16:31 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:16:31 INFO - Using env: {'DISPLAY': ':0', 09:16:31 INFO - 'HOME': '/home/cltbld', 09:16:31 INFO - 'LANG': 'en_US.UTF-8', 09:16:31 INFO - 'LANGUAGE': 'en_US:en', 09:16:31 INFO - 'LOGNAME': 'cltbld', 09:16:31 INFO - 'MAIL': '/var/mail/cltbld', 09:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:31 INFO - 'MOZ_NO_REMOTE': '1', 09:16:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:31 INFO - 'NO_EM_RESTART': '1', 09:16:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:31 INFO - 'PWD': '/builds/slave/test', 09:16:31 INFO - 'SHELL': '/bin/bash', 09:16:31 INFO - 'SHLVL': '1', 09:16:31 INFO - 'TERM': 'linux', 09:16:31 INFO - 'TMOUT': '86400', 09:16:31 INFO - 'USER': 'cltbld', 09:16:31 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', 09:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:31 INFO - '_': '/tools/buildbot/bin/python'} 09:16:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:31 INFO - Running setup.py (path:/tmp/pip-DOp1C9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:31 INFO - Running setup.py (path:/tmp/pip-YwI22b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:31 INFO - Running setup.py (path:/tmp/pip-hO3x5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:32 INFO - Running setup.py (path:/tmp/pip-zSNoEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:32 INFO - Running setup.py (path:/tmp/pip-BaApfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:32 INFO - Running setup.py (path:/tmp/pip-KAK8Js-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:32 INFO - Running setup.py (path:/tmp/pip-iIjvgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:32 INFO - Running setup.py (path:/tmp/pip-zhTNjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:32 INFO - Running setup.py (path:/tmp/pip-ogNav8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:32 INFO - Running setup.py (path:/tmp/pip-ZQOcLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:32 INFO - Running setup.py (path:/tmp/pip-aKJplp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:32 INFO - Running setup.py (path:/tmp/pip-p0Vl5x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:32 INFO - Running setup.py (path:/tmp/pip-Fidbdt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:32 INFO - Running setup.py (path:/tmp/pip-CoP6a7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:32 INFO - Running setup.py (path:/tmp/pip-hej_NP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:32 INFO - Running setup.py (path:/tmp/pip-pzbNA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:32 INFO - Running setup.py (path:/tmp/pip-pEy3Qe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:16:33 INFO - Running setup.py install for manifestparser 09:16:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:16:33 INFO - Running setup.py install for mozcrash 09:16:33 INFO - Running setup.py install for mozdebug 09:16:33 INFO - Running setup.py install for mozdevice 09:16:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:16:33 INFO - Running setup.py install for mozfile 09:16:33 INFO - Running setup.py install for mozhttpd 09:16:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:16:33 INFO - Running setup.py install for mozinfo 09:16:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:16:33 INFO - Running setup.py install for mozInstall 09:16:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:16:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:16:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:16:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:16:33 INFO - Running setup.py install for mozleak 09:16:33 INFO - Running setup.py install for mozlog 09:16:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:16:34 INFO - Running setup.py install for moznetwork 09:16:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:16:34 INFO - Running setup.py install for mozprocess 09:16:34 INFO - Running setup.py install for mozprofile 09:16:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:16:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:16:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:16:34 INFO - Running setup.py install for mozrunner 09:16:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:16:34 INFO - Running setup.py install for mozscreenshot 09:16:34 INFO - Running setup.py install for moztest 09:16:34 INFO - Running setup.py install for mozversion 09:16:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:16:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:16:34 INFO - Cleaning up... 09:16:34 INFO - Return code: 0 09:16:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:34 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:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:34 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:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:34 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 0x28341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ba0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba4090>, '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 0x2ba4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b9ef90>, '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': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', '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:16:34 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:16:34 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:16:34 INFO - Using env: {'DISPLAY': ':0', 09:16:34 INFO - 'HOME': '/home/cltbld', 09:16:34 INFO - 'LANG': 'en_US.UTF-8', 09:16:34 INFO - 'LANGUAGE': 'en_US:en', 09:16:34 INFO - 'LOGNAME': 'cltbld', 09:16:34 INFO - 'MAIL': '/var/mail/cltbld', 09:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:34 INFO - 'MOZ_NO_REMOTE': '1', 09:16:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:34 INFO - 'NO_EM_RESTART': '1', 09:16:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:34 INFO - 'PWD': '/builds/slave/test', 09:16:34 INFO - 'SHELL': '/bin/bash', 09:16:34 INFO - 'SHLVL': '1', 09:16:34 INFO - 'TERM': 'linux', 09:16:34 INFO - 'TMOUT': '86400', 09:16:34 INFO - 'USER': 'cltbld', 09:16:34 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', 09:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:34 INFO - '_': '/tools/buildbot/bin/python'} 09:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:35 INFO - Running setup.py (path:/tmp/pip-LaKLWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:35 INFO - Running setup.py (path:/tmp/pip-l9LLGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:35 INFO - Running setup.py (path:/tmp/pip-TO4dNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:35 INFO - Running setup.py (path:/tmp/pip-jcjnJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:35 INFO - Running setup.py (path:/tmp/pip-9jQVfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:35 INFO - Running setup.py (path:/tmp/pip-hLqOZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:35 INFO - Running setup.py (path:/tmp/pip-22GMvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:35 INFO - Running setup.py (path:/tmp/pip-R6_5fT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:35 INFO - Running setup.py (path:/tmp/pip-AZheZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:35 INFO - Running setup.py (path:/tmp/pip-Y4V3bq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:35 INFO - Running setup.py (path:/tmp/pip-1uAjB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:35 INFO - Running setup.py (path:/tmp/pip-HGu5lo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:35 INFO - Running setup.py (path:/tmp/pip-5GSoOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:35 INFO - Running setup.py (path:/tmp/pip-TVO1zS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:36 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:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:36 INFO - Running setup.py (path:/tmp/pip-aawRLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:36 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:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:36 INFO - Running setup.py (path:/tmp/pip-xFRvtV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:36 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:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:36 INFO - Running setup.py (path:/tmp/pip-IQTL2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:36 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:16:36 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:16:36 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:16:36 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:16:36 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:16:36 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:16:36 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:16:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:37 INFO - Downloading blessings-1.6.tar.gz 09:16:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:16:37 INFO - Installing collected packages: blessings 09:16:37 INFO - Running setup.py install for blessings 09:16:37 INFO - Successfully installed blessings 09:16:37 INFO - Cleaning up... 09:16:37 INFO - Return code: 0 09:16:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:16:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:37 INFO - Reading from file tmpfile_stdout 09:16:37 INFO - Current package versions: 09:16:37 INFO - argparse == 1.2.1 09:16:37 INFO - blessings == 1.6 09:16:37 INFO - blobuploader == 1.2.4 09:16:37 INFO - docopt == 0.6.1 09:16:37 INFO - functools32 == 3.2.3-2 09:16:37 INFO - jsonschema == 2.5.1 09:16:37 INFO - manifestparser == 1.1 09:16:37 INFO - mozInstall == 1.13 09:16:37 INFO - mozcrash == 1.0 09:16:37 INFO - mozdebug == 0.1 09:16:37 INFO - mozdevice == 0.51 09:16:37 INFO - mozfile == 1.2 09:16:37 INFO - mozhttpd == 0.7 09:16:37 INFO - mozinfo == 0.10 09:16:37 INFO - mozleak == 0.1 09:16:37 INFO - mozlog == 3.5 09:16:37 INFO - moznetwork == 0.27 09:16:37 INFO - mozprocess == 0.25 09:16:37 INFO - mozprofile == 0.28 09:16:37 INFO - mozrunner == 6.13 09:16:37 INFO - mozscreenshot == 0.1 09:16:37 INFO - mozsystemmonitor == 0.3 09:16:37 INFO - moztest == 0.8 09:16:37 INFO - mozversion == 1.4 09:16:37 INFO - psutil == 3.1.1 09:16:37 INFO - requests == 1.2.3 09:16:37 INFO - wsgiref == 0.1.2 09:16:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:37 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:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:37 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:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:37 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 0x28341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ba0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba4090>, '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 0x2ba4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b9ef90>, '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': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', '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:16:37 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:16:37 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:16:37 INFO - Using env: {'DISPLAY': ':0', 09:16:37 INFO - 'HOME': '/home/cltbld', 09:16:37 INFO - 'LANG': 'en_US.UTF-8', 09:16:37 INFO - 'LANGUAGE': 'en_US:en', 09:16:37 INFO - 'LOGNAME': 'cltbld', 09:16:37 INFO - 'MAIL': '/var/mail/cltbld', 09:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:37 INFO - 'MOZ_NO_REMOTE': '1', 09:16:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:37 INFO - 'NO_EM_RESTART': '1', 09:16:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:37 INFO - 'PWD': '/builds/slave/test', 09:16:37 INFO - 'SHELL': '/bin/bash', 09:16:37 INFO - 'SHLVL': '1', 09:16:37 INFO - 'TERM': 'linux', 09:16:37 INFO - 'TMOUT': '86400', 09:16:37 INFO - 'USER': 'cltbld', 09:16:37 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', 09:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:37 INFO - '_': '/tools/buildbot/bin/python'} 09:16:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:38 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:16:38 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:16:38 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:16:38 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:16:38 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:16:38 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:16:38 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:16:38 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:16:38 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:16:38 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 09:16:39 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:16:39 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:16:39 INFO - Installing collected packages: simplejson 09:16:39 INFO - Running setup.py install for simplejson 09:16:39 INFO - building 'simplejson._speedups' extension 09:16:39 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:16:40 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:16:40 INFO - Successfully installed simplejson 09:16:40 INFO - Cleaning up... 09:16:40 INFO - Return code: 0 09:16:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:16:40 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:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:40 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:16:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:40 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 0x28341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ba0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba4090>, '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 0x2ba4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b9ef90>, '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': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', '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:16:40 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:16:40 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:16:40 INFO - Using env: {'DISPLAY': ':0', 09:16:40 INFO - 'HOME': '/home/cltbld', 09:16:40 INFO - 'LANG': 'en_US.UTF-8', 09:16:40 INFO - 'LANGUAGE': 'en_US:en', 09:16:40 INFO - 'LOGNAME': 'cltbld', 09:16:40 INFO - 'MAIL': '/var/mail/cltbld', 09:16:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:40 INFO - 'MOZ_NO_REMOTE': '1', 09:16:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:40 INFO - 'NO_EM_RESTART': '1', 09:16:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:40 INFO - 'PWD': '/builds/slave/test', 09:16:40 INFO - 'SHELL': '/bin/bash', 09:16:40 INFO - 'SHLVL': '1', 09:16:40 INFO - 'TERM': 'linux', 09:16:40 INFO - 'TMOUT': '86400', 09:16:40 INFO - 'USER': 'cltbld', 09:16:40 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', 09:16:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:40 INFO - '_': '/tools/buildbot/bin/python'} 09:16:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:16:40 INFO - Cleaning up... 09:16:40 INFO - Return code: 0 09:16:40 INFO - Running post-action listener: _resource_record_post_action 09:16:40 INFO - Running post-action listener: _start_resource_monitoring 09:16:40 INFO - Starting resource monitoring. 09:16:40 INFO - [mozharness: 2017-09-01 16:16:40.547910Z] Finished create-virtualenv step (success) 09:16:40 INFO - [mozharness: 2017-09-01 16:16:40.548379Z] Running install step. 09:16:40 INFO - Running pre-action listener: _resource_record_pre_action 09:16:40 INFO - Running main action method: install 09:16:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:40 INFO - Reading from file tmpfile_stdout 09:16:40 INFO - Detecting whether we're running mozinstall >=1.0... 09:16:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:16:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:16:40 INFO - Reading from file tmpfile_stdout 09:16:40 INFO - Output received: 09:16:40 INFO - Usage: mozinstall [options] installer 09:16:40 INFO - Options: 09:16:40 INFO - -h, --help show this help message and exit 09:16:40 INFO - -d DEST, --destination=DEST 09:16:40 INFO - Directory to install application into. [default: 09:16:40 INFO - "/builds/slave/test"] 09:16:40 INFO - --app=APP Application being installed. [default: firefox] 09:16:40 INFO - mkdir: /builds/slave/test/build/application 09:16:40 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:16:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:16:57 INFO - Reading from file tmpfile_stdout 09:16:57 INFO - Output received: 09:16:57 INFO - /builds/slave/test/build/application/firefox/firefox 09:16:57 INFO - Running post-action listener: _resource_record_post_action 09:16:57 INFO - [mozharness: 2017-09-01 16:16:57.400606Z] Finished install step (success) 09:16:57 INFO - [mozharness: 2017-09-01 16:16:57.400833Z] Running setup-mitmproxy step. 09:16:57 INFO - Running pre-action listener: _resource_record_pre_action 09:16:57 INFO - Running main action method: setup_mitmproxy 09:16:57 INFO - Skipping: mitmproxy is not required 09:16:57 INFO - Running post-action listener: _resource_record_post_action 09:16:57 INFO - [mozharness: 2017-09-01 16:16:57.401369Z] Finished setup-mitmproxy step (success) 09:16:57 INFO - [mozharness: 2017-09-01 16:16:57.401473Z] Running run-tests step. 09:16:57 INFO - Running pre-action listener: _resource_record_pre_action 09:16:57 INFO - Running pre-action listener: _set_gcov_prefix 09:16:57 INFO - Running main action method: run_tests 09:16:57 WARNING - Try message not found. 09:16:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:16:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:16:57 INFO - Python 2.7.3 09:16:57 INFO - Return code: 0 09:16:57 INFO - grabbing minidump binary from tooltool 09:16:57 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:16:57 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 0x2ba4090>, '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 0x2ba4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b9ef90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:16:57 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:16:57 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:16:57 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:16:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:16:57 INFO - Return code: 0 09:16:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:16:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:16:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:16:57 INFO - ENV: RUST_BACKTRACE is now full 09:16:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:16:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:16:57 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-058', '--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:16:57 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-058 --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:16:57 INFO - Using env: {'DISPLAY': ':0', 09:16:57 INFO - 'HOME': '/home/cltbld', 09:16:57 INFO - 'LANG': 'en_US.UTF-8', 09:16:57 INFO - 'LANGUAGE': 'en_US:en', 09:16:57 INFO - 'LOGNAME': 'cltbld', 09:16:57 INFO - 'MAIL': '/var/mail/cltbld', 09:16:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:16:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:16:57 INFO - 'MOZ_ACCELERATED': '1', 09:16:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:57 INFO - 'MOZ_NO_REMOTE': '1', 09:16:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:16:57 INFO - 'MOZ_WEBRENDER': '1', 09:16:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:57 INFO - 'NO_EM_RESTART': '1', 09:16:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:57 INFO - 'PWD': '/builds/slave/test', 09:16:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:16:57 INFO - 'RUST_BACKTRACE': 'full', 09:16:57 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:16:57 INFO - 'SHELL': '/bin/bash', 09:16:57 INFO - 'SHLVL': '1', 09:16:57 INFO - 'TERM': 'linux', 09:16:57 INFO - 'TMOUT': '86400', 09:16:57 INFO - 'USER': 'cltbld', 09:16:57 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', 09:16:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:57 INFO - '_': '/tools/buildbot/bin/python'} 09:16:57 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-058', '--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:16:57 INFO - mozversion application_buildid: 20170901150655 09:16:57 INFO - mozversion application_changeset: 34933f6390d52779ea498a6a5fd5f34d54734780 09:16:57 INFO - mozversion application_display_name: Nightly 09:16:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:16:57 INFO - mozversion application_name: Firefox 09:16:57 INFO - mozversion application_remotingname: firefox 09:16:57 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:16:57 INFO - mozversion application_vendor: Mozilla 09:16:57 INFO - mozversion application_version: 57.0a1 09:16:57 INFO - mozversion platform_buildid: 20170901150655 09:16:57 INFO - mozversion platform_changeset: 34933f6390d52779ea498a6a5fd5f34d54734780 09:16:57 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:16:57 INFO - mozversion platform_version: 57.0a1 09:16:57 INFO - using testdate: 1504282617 09:16:57 INFO - actual date: 1504282617 09:16:57 INFO - starting webserver on 'localhost:56209' 09:16:57 INFO - SUITE-START | Running 2 tests 09:16:57 INFO - TEST-START | dromaeo_css 09:16:57 INFO - Initialising browser for dromaeo_css test... 09:16:57 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56209/getInfo.html -profile /tmp/tmpYX2tdp/profile 09:16:57 INFO - TEST-INFO | started process 28964 (/builds/slave/test/build/application/firefox/firefox http://localhost:56209/getInfo.html) 09:17:03 INFO - TEST-INFO | 28964: exit 0 09:17:03 INFO - Browser initialized. 09:17:03 INFO - Running cycle 1/1 for dromaeo_css test... 09:17:03 INFO - Using env: {'DISPLAY': ':0', 09:17:03 INFO - 'HOME': '/home/cltbld', 09:17:03 INFO - 'JSGC_DISABLE_POISONING': '1', 09:17:03 INFO - 'LANG': 'en_US.UTF-8', 09:17:03 INFO - 'LANGUAGE': 'en_US:en', 09:17:03 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 09:17:03 INFO - 'LOGNAME': 'cltbld', 09:17:03 INFO - 'MAIL': '/var/mail/cltbld', 09:17:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:17:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:17:03 INFO - 'MOZ_ACCELERATED': '1', 09:17:03 INFO - 'MOZ_CRASHREPORTER': '1', 09:17:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:03 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 09:17:03 INFO - 'MOZ_NO_REMOTE': '1', 09:17:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:17:03 INFO - 'MOZ_WEBRENDER': '1', 09:17:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:03 INFO - 'NO_EM_RESTART': '1', 09:17:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:03 INFO - 'PWD': '/builds/slave/test', 09:17:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:17:03 INFO - 'RUST_BACKTRACE': 'full', 09:17:03 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:17:03 INFO - 'SHELL': '/bin/bash', 09:17:03 INFO - 'SHLVL': '1', 09:17:03 INFO - 'TERM': 'linux', 09:17:03 INFO - 'TMOUT': '86400', 09:17:03 INFO - 'USER': 'cltbld', 09:17:03 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', 09:17:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:03 INFO - '_': '/tools/buildbot/bin/python'} 09:17:03 INFO - TEST-INFO | started process 29156 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYX2tdp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:17:04 INFO - PID 29156 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 09:17:04 INFO - PID 29156 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 09:17:05 INFO - PID 29156 | 09:17:05 INFO - PID 29156 | (/builds/slave/test/build/application/firefox/firefox:29217): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:17:05 INFO - PID 29156 | 09:17:15 INFO - PID 29156 | RSS: Main: 211562496 09:17:15 INFO - PID 29156 | 09:17:18 INFO - PID 29156 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 09:20:13 INFO - PID 29156 | Cycle 1(1): loaded http://localhost:56209/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56209/tests/dromaeo/cssquery-ext.html) 09:20:15 INFO - PID 29156 | RSS: Main: 220135424 09:20:15 INFO - PID 29156 | 09:23:17 INFO - PID 29156 | Cycle 1(1): loaded http://localhost:56209/tests/dromaeo/cssquery-ext.html (next: http://localhost:56209/tests/dromaeo/cssquery-jquery.html) 09:23:18 INFO - PID 29156 | RSS: Main: 218263552 09:23:18 INFO - PID 29156 | 09:26:20 INFO - PID 29156 | Cycle 1(1): loaded http://localhost:56209/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56209/tests/dromaeo/cssquery-mootools.html) 09:26:20 INFO - PID 29156 | RSS: Main: 220577792 09:26:20 INFO - PID 29156 | 09:29:22 INFO - PID 29156 | Cycle 1(1): loaded http://localhost:56209/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56209/tests/dromaeo/cssquery-prototype.html) 09:29:23 INFO - PID 29156 | RSS: Main: 219045888 09:29:23 INFO - PID 29156 | 09:32:27 INFO - PID 29156 | Cycle 1(1): loaded http://localhost:56209/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56209/tests/dromaeo/cssquery-yui.html) 09:32:27 INFO - PID 29156 | RSS: Main: 219627520 09:32:27 INFO - PID 29156 | 09:35:30 INFO - PID 29156 | Cycle 1(1): loaded http://localhost:56209/tests/dromaeo/cssquery-yui.html (next: http://localhost:56209/tests/dromaeo/cssquery-dojo.html) 09:35:30 INFO - PID 29156 | RSS: Main: 222191616 09:35:30 INFO - PID 29156 | 09:35:30 INFO - PID 29156 | __start_tp_report 09:35:30 INFO - PID 29156 | _x_x_mozilla_page_load 09:35:30 INFO - PID 29156 | _x_x_mozilla_page_load_details 09:35:30 INFO - PID 29156 | |i|pagename|runs| 09:35:30 INFO - PID 29156 | |0;dojo.html;3284.9343013139733;3301.7523685723577;3310.9172270693243;3313.718333941614;3356.546866173066;6276.874462510747;6443.001334793109;6453.741850325993;6460.192475940507;6497.415232629062;6209.223847019122;6273.2472103347545;6284.5600807943465;6307.117003619485;6311.116443697874;5666.093425051992;5672.290963629546;5674.148877668342;5692.857678558028;5729.140628905666;5777.306723193223;5802.448767367094;5893.263342082239;5957.136215248786;5983.880322393549;5970.34326224115;5988.071848863411;5993.850153746148;6003.969980150093;6061.54538409621;5694.743736531866;5845.357010728816;5899.439553242435;5924.940750592504;5988.9101663475085;5290;5734.111212762028;5746.827595172152;5861.85345366365;5882.882342353172;6283.214598175228;6319.020551814476;6343.746250149989;6428.678566071724;6478.255000674889;6549.508786841006;6661.200655921272;6662.067310576524;6861.725530978755;6867.656617169122;6609.272979972199;6664.300248473937;6667.266600673922;6692.230393504712;6694.364035416627;6152.78465253714;6205.596636218676;6209.875802483925;6210.937890621126;6214.40963108504;6815.2162501312;6826.385625293748;6830.590164590041;6831.351021652935;6841.931580684229;6469.967650161719;6559.6064236145985;6560.442362399154;6560.770373037016;6566.409023187936;6607.306232845578;6616.206055273398;6626;6662.467002639777;6887.758928437481;6809.285025072296;6839.110915580943;6917.827054323682;6931.1682598088555;6941.930580694229;6571.57284776488;6839.452843772487;6855.040294358794;6938.306169383122;6957.339052789976;5323.3079699639575;5331.147016477423;5342.439043900412;5344.465553444625;5348.037353276448;4483.686141970096;4662.626989840873;4683.625309975194;4721.032188401438;4785.665003449725;5784.739686714108;5821.446962538552;5842;5864;6071.878562428727;6697.296783837724;6700.765473208415;6724.764633237911;6727.697253623599;6750.594964302158;5746.741396637161;5869.031609784423;5909.025010873243;5913.704314784243;5921.940780592225;5735.8566035849435;5809.941900581025;5816.185733997244;5822.737976791054;5852.941470585325;6657.101291325609;6680.63256520896;6725.6973436195485;6763.695633696496;6768.830779230559;6573.211214654081;6691.799246022627;6834.419074378733;6881;6988.091548098715;6602.471802255904;6605.834854128685;6622;6663.733450661919;6665.966775149859;6840.31596840312;6841.657917104026;6851.725930962705;6874.896876546756;6876.931230687829;6355.30091689923;6896.724131034703;6982.615956122462;6988.860222795617;7000.439964802906;6626;6675.499337549606;6730.966345168243;6784.08414863987;6788.558743681645;6425.4217120457515;6592;6625.536212465081;6672.432843208382;6691.297413771678;6455.128557644658;6567.540272180901;6638;6654.434373078149;6664.0670306158145;6360.363963603602;6587.308332625005;6659.067730517828;6686.732530698718;6711.060451536691;6423.164988551458;6445.387688169616;6525.738970441129;6529.347065293433;6549.508786841101 09:35:30 INFO - PID 29156 | |1;ext.html;779.4504874063786;832.9333653307737;858.1761508951406;883.3507372081523;884.9999999999998;38980;40231.195376092495;40254.39618405722;40468.59531404685;40543.39184912229;36970.26059478809;37350.06624834381;37534;37594.24811503775;37748.811255943685;6885.552439091455;6996.6851491683;6999.12510936133;7012.948057791321;7013.08829852121;6823.590584564942;6832.863342733142;6900.344467275626;6925.480588955823;6928.757493487729;6891.758788442406;7404.592747398891;7586.241375862425;7597.278258565455;7607.505512141721;7473.51422157558;7488.427430239656;7534.660940257701;7543.585102819343;7598.620068996528;7451.366633836131;7484.438667099989;7487.887681684779;7507.249275072449;7512.5492470451945;35138;36352.81823590892;37404.812975935216;37425.25149496995;37500;3483.738719596015;3490.057684425191;3521.559805024372;3522.84147213376;3531.858725650971;31629.841850790828;31701.365972680418;31881.681183188102;31909;31911.840440797878;5105.336306280197;5110.080185566597;5121;5163.457836927144;5237.292965449654;189706;192591;196302;196599;196724.0163799186;46967.060658786635;47114.29328560075;47399;47426.28860567094;47489.28766068512;48952;49078.5092149074;49140.754296229;49148.262776058385;49292.753536232805;14006.859931400759;14184.290785460686;14291.356888940023;14296.070755400866;14315.928420357832;11461.770764584662;11494.42527873603;11507.71230719222;11510.884891150981;11512.251703639236;13628.11417257895;13803.792943105862;13846.169229846242;13859.514916977858;13971.79042314366;5581.665100094007;5696.259486266758;5736.741846616912;5833.270841144857;5839.970800146057;989.2531138990016;1002.2132625888643;1003.7239759066169;1005.0602686488157;1011.8937511561327;23548.64677029846;29038.854805725838;29385.85307073451;30181.849090754844;30246.941357052398;1089.6349722842779;1089.809283375406;1094.5293523784849;1101.4823033174396;1104.089126470665;12388;12615.30615816139;12621;12635.115541911979;12694.682632934251;230226;233652;234627;234678;234829;42569.787151064666;49165.508344916096;49348.75325623349;49555.75222123866;49626.75186624044;1273.40150129439;1278.571678487717;1427.421894132878;1436.080908218741;1441.7621092519616;27929;28106.578401323586;28540.57189142123;28628;28838.134855954355;49042.264366035255;50080.49919500904;50283.497165029345;50330.2450463236;50388.49611503838;206271;206391;206409;206442;210770;34361.31277374489;34710;34769.826150869085;35081.47377789284;35140.64859351374;42212.366814497196;43652.781736091114;43950.560494395926;44056.339154912064;44078.33882491701;32495.512567311984;33072.66927330792;33208.66791332152;33285.16787080342;33400;31562.210944725648;32352.514712278862;32419.837900810344;32980;33014.83492582522;34910;36061.27877442384;36396.27207455783;36423.45364819583;36801.63198367982;31346.52980205253;31797.523037154922;32205.677943221206;32372.838135810114;32440.513392299606;22531.66202506931;23465.178718744763;23817.761822382246;24248.51502969966;24364.634530481704 09:35:30 INFO - PID 29156 | |2;jquery.html;552.9668219906805;555.6388347574076;564.7176411794105;565.2115299157674;570.5863249144368;21306;22057.22799702011;22288.442788930264;22300.442488937744;22342.10631574739;25730.228093157188;26149.869250653766;26158.73841261587;26196.083137090205;26425.471490570177;6463.515236357281;6604.900926486107;6606.735730570771;6613.801585952405;6622.337766223363;6392.360763923617;6491.091247225392;6528.543001989862;6691.866162676743;6705.899411508832;6586;6643.269240383554;6652.268250492442;6652.567583107082;6655.267920528762;6630.171228596426;6641.900371494431;6641.966790166042;6644.169478815148;6646.401823835829;5984.162217289583;6076.301225359096;6087.726052327655;6145.78489752861;6193.659348735818;28390.85804570964;29086;29347.119586412442;29678.70321296781;29978.55032174519;1947.9123439445118;1979.0302751651586;1980.8316293115101;1982.5142840004148;1985.176151896961;19342.516437089045;19968.800311996984;19983.90008049965;19992.20031198765;20002.39992800218;2343.083249282328;2375.0855920470744;2376.918502081549;2400.291913885408;2461.815363847719;132327.33836330756;134996;136892;137204;137672.31163844216;14423.711525769426;14469.49356772508;14744.778828317583;14914.70170596582;15195.92402038005;22820.885895570416;22864;22890.885545572164;22953.540929181323;23007.07971681114;9486.762830929143;9763.584898138113;9796.16732577739;9802.75493112678;9811.75470613226;8906.688265910661;8928.196462318292;8990.730278091667;9002.279817614575;9040.141186587263;8374.958125209336;8880.333974951904;9167.17495425415;9244.815103697889;9612.471314077793;2609.178108895692;2623.9475210496066;2642.6828780546457;2703.837769733783;2729.5769155780886;922.4280945813639;983.7540614846289;988.1205726903062;991.5191132300828;992.4938281476354;19338.4198474046;19978.800211997986;20358.389248322575;20360.083796229206;20646.483837904172;1044.1438020822852;1044.1907521670614;1057.9259451838298;1059.1103473082649;1070.9892901071046;10952.178586606036;11276.774464510663;11321.94339028316;11345.262557933707;11433.656990290305;457935;462499;465209;471956;477685;114951;127033;130910;131998;132119;1215.2283300104498;1244.9875501244871;1255.453877563263;1263.6524955637274;1266.2022925557023;20746;20887;21082.57834843264;21382.572348553254;21600.243991460222;59607.40392596192;60365;61292.08061878986;61430.38569614425;62340;76334.23665763272;76503;76673.61663191872;77073.22926770886;77397.61301193458;14128.50550230737;14864.777028344368;14913.179775112472;15027.346991830671;15125.243737812845;23936;24011;24278;24437.877810610833;24694;18322;18573;18788.530286742392;18792.7181092281;19062;16992.810503264653;17044.829551704323;17243.482695519153;17273.654526909642;17602.73595896037;19018.334358297394;19323.710144347566;19459.318923837596;19706.31027914016;19891;14095;14831.777523336943;14835.184064876536;14889.436609155882;14939.626509336918;17308.134593270537;18264.81735182631;18698.532536686693;18814.52963675864;18858.057097144814 09:35:30 INFO - PID 29156 | |3;mootools.html;1320.6632308761264;1373.9106958047726;1377.7726675098609;1380.2753554383953;1393.1153717389457;14728.6317842054;15268.083914965096;15284.923575382107;15305.84694153058;15397.846021539795;14806.851931480682;15790.684186316266;15864.444744433948;15873.84126158741;15895.841041589581;2672.4521473098066;2726.2502811726736;2727.059164588214;2728.4133911209087;2758.0760445250767;2592.1316359019656;2691.946161076777;2697.8381297122137;2697.8381297122237;2706.07993282284;2652.2308530526125;2669.626252324676;2691.5020721166547;2692.6634170728657;2708.255229811798;2574.909878154265;2579.85810780407;2598.3244356467267;2607;2609.6607441032734;2582.883770230344;2597.5584150694426;2605.830621009647;2606.882690278942;2616.0974463809957;14310.713785724227;14466.421343146367;14702.632434189123;14868.851311486855;14871.405143794234;7941.36469082472;8138.430309878311;8142.715004974797;8160;8199.95900020496;5441.483059109379;5573.442655734435;5708.543316534668;5747.109198074305;5779.739911703941;1910.4173227165709;1923.0961448119363;1957.5106223444138;2014.9899250503654;2018.3137733170793;25520.617190742152;26048.73951260482;26307.868460657766;26452.206433807016;27223.183304500897;6703.698333575001;6831.11195544576;6934.826629334307;7063.89404158938;7082.468814838908;16921.83078169227;16936.66126677484;16973;16973.830261697472;16997.915010425117;7809.648565814552;7961.36309095271;7984.720534781255;7992.560409177435;8004.399670024772;7299.635018249066;7324.450666200057;7426.108866935894;7445.106587209461;7563.357114645317;5186.611004174702;5346.759395827197;5347.866303342447;5378.542823859937;5399.4600539945695;1483.9109653420828;1513.137511618367;1535.1479928639712;1576.4167258114571;1588.348777001424;891.5943245823134;922.3912217936264;926.2451102351562;934.3319526538422;943.2595412601075;8245.381596380175;8427.662893484192;8463.322934165375;8465.36509761758;8481.533515656574;959.3524371049514;990.762217067913;993.279872092727;993.7962717642865;997.0627610046533;5466.562674985991;5550.750216240279;5623.493885550321;5652.943470565324;5833.88332233353;20926.895365523076;21225.15099396038;21264;21930.561388772134;22130.778692212873;20379.18483260653;20895.477613059797;21323.253686120912;21396.57206855854;21599.56800863974;9312.301577381588;9597.136257736836;10727.83908241377;10728.517216725266;10924.344539327665;7683.116441609286;8564.929383827022;8648.135186481302;8671.956640216758;8801.735947921572;17011.149442528073;17547.82452175513;17615.383461578782;17650.823491764917;17809.287628495233;17927.820721792614;17951.192196350934;18128;18223.362182323555;18404.539886502946;10906.945465272624;10978.505967231335;11033;11155.386453745123;11229.157813163882;13591.660208494866;13829.170249785046;13834.65413364674;13839.584812455643;13915.095518791246;11640.476178571815;11680.182387232811;11734.237274577124;11833.763324733629;12082.275063496218;11233.269837460539;11288.492017859051;11515.53937842477;11574.363410012193;11703.180777345504;9142.542872856464;9199.264058875407;9206.9539652304;9213;9214.170724634547;7001.50989430735;7060;7132.322429369202;7166.641667916479;7172.390346820579;6759.966200169164;6905.482088843457;6929.237783843874;6989.790306290819;6999.755008574879 09:35:30 INFO - PID 29156 | |4;prototype.html;485.1776239274427;490.78650786907696;500.2296463446293;508.7761384990605;524.9317588713469;6324.3043265240785;6387.297397286301;6404.1674582304295;6443.484521238302;6460.547761656686;5972;6169.175111430908;6192.380761923817;6215.347388524207;6242.812715618527;5010.298558201855;5048;5059.316992206061;5073.822416215433;5103.617228707862;4878.121938051149;5029.2958985742025;5044;5084.262781896623;5108.565771909373;5025.57282630975;5041.076175818035;5087.321184009286;5088.735512696851;5091.879786446994;4999.650024498286;5027.296178535008;5041.143005689041;5043.64694471389;5082.491750824925;4490.079533695617;4499.302608095718;4528.547145285445;4534.8866278342975;4543.182227199103;6272.498200144023;6275.780347687854;6287.2140982377205;6327.5570710050315;6372.808815735535;5817.214676018768;5826.368616787307;5842.649441033552;5846.239988801429;5895.734691938873;6620.437262832665;6664.666766661648;6698.196216454056;6710.765123220713;6735.764248251287;4854.2961270615915;4860.149473842063;4939.283803848458;4939.753012349368;4963.875903102451;7025.121859767529;7025.929740702579;7026.402755765766;7049.894251586179;7135.571865688075;5079.822206222764;5121.206213036985;5123.410807757081;5125.948740512622;5151.5878729701535;5171.818986335536;5180.352455943007;5183.792648294101;5184.818531351385;5209.739513024334;5699.658020518782;5714.314282286069;5736.77052917887;5786.739596718157;5813.215215945793;6655.667216639149;6732.798016059526;6800.727970881109;6801;6805.843006688776;1642.3184378482795;1675.9245833937503;1697.1514242878561;1721.3544920654754;1766.9663247000392;1211.3882489342825;1220.6216073017215;1228.1341654133994;1241.472374240944;1254.2474515290753;5871.823845284648;5905.704714764244;5941.70291485424;5983.311919129269;5993.70031498432;6720.563163394364;6783.8806596911945;6800.625965571941;6823.454123670094;6823.624700641512;5765.279340082489;5924.377940316291;5947.970260148758;5948.196993405835;5951.7619295227705;3632.727545434103;3640.7087433005827;3689.409694448929;3706.2772759312215;3707.2770809692393;6655;6677.565958212701;6696.354939798753;6711.362420570135;6814.591124532544;7013.4038606719005;7076.575405475688;7090.680919358641;7113.292809725629;7154.964225178841;6671.532262902154;6680.565763225375;6725.007371719636;6798.76204332846;6869.931300687129;6922.238553758982;6941.167059952838;6991.335823096798;7022.6488675565;7035.472339574655;6753.3246675332075;6810.421114205348;6822.556533825285;6889.242183359726;6975.96512017457;6663.7672030674485;6669.699863506057;6694.359881828983;6734.764283250062;6744.022116793136;4992.176290912104;5059.974700126623;5079.720615366043;5086.3896332440345;5099.6175286852895;6970.860582788418;7024.26245244242;7046.929530704832;7053.365197132282;7067.858642827217;6520.706568204537;6605.966970165311;6664.50016248793;6737.305077968852;6774.3225677431865;6614.66926653675;6617.338266173344;6651.368120028589;6681.732730690718;6694.230163531256;6404.711787969646;6527.314631963575;6642.368974947372;6644.302348253364;6720.563163394364;6282.8429289266305;6638.668066596748;6642.568233064835;6674.16572928384;6702.36327548882;6387.796210307437;6423.710933008119;6452.967735161482;6509.544331896722;6574.178227721535 09:35:30 INFO - PID 29156 | |5;yui.html;878.0560897035684;892.2415946445518;902.963881444742;907.2651152566422;924.6948506992691;17744.91127544362;18575.907120464395;18659.3469228577;18922.810771892277;18925.337613183543;13375.933120334408;13381.26403047832;16228.509491389275;20029.298974535894;20211.494712632193;981.1267971505366;999.8650182225416;1000.324780772979;1002.2533212756507;1002.8044531316397;950.9762255943588;987.2052997337141;992.9900700993006;993.3642468819961;995.7162208770502;1034.151995363802;1034.462079718546;1040.953157107928;1045.7333379988092;1048.0567489259688;1036.8392899100652;1039.6621098143096;1039.844023396489;1043.2488608202082;1043.9634612788554;754.3550264523808;763.7594157840263;766.1495739728897;768.9500182488121;770.5453782268488;19598;19837.80162198374;19862.90068549648;19958.401247962585;20087.598248035105;20465.795342046687;20580.691289630668;21143.5771284575;21262.149514019577;21282.787172128392;16587.66824663512;17074.658506829917;17111.31554737809;17220.74168887455;17265.482035538953;3716.739828212026;3729.7948612826285;3740.7194460415576;3743.1390780120723;3747.025773298935;131369;131746;132619;133582.33208833894;134531.32734336265;15843.52469425896;15974.121423321829;15997.68004639924;16094.597635059217;16231.756523652155;28192.577111342936;28486.715132848407;28519.714802851708;29316;29883;7903.209679032051;8007.83984320322;8050.993625796776;8077.111517733045;8118.35053195743;3998.7800670962793;4014.0566966762785;4114.6091121343425;4132.772697501667;4233.830646774156;5027;5137;5146.588272938156;5152.046871328816;5201.661891977085;1033.7777377863645;1037.3931250218557;1075.306427354352;1083.090204228452;1086.315621158665;670.7317073170673;690.1132045321738;695.5687473766196;703.0684343245221;703.4407645921505;10845.078168355778;11208.66374008781;11313.717157070974;11461.312321260752;11676.124290678063;678.7760039187057;691.4295706042434;695.4888157204551;708.3164746020116;709.0498731699547;5331.173668081454;5453.563714902798;5465.07093794056;5468.398476167618;5506.284183056177;113709.43145284221;115442;117815;119844;120900.39549802194;95229;95940.52029739806;100958.49520752496;103494;106511;85902.78228645925;87339;89262;89707.10292897117;91440.54279728558;10683.673112389495;10935.726606834893;10999.670009899715;11647.184697071123;11987.041036716868;40084.59915400925;40698.38952415657;41222.38166427446;41881.37177942273;42070;55043.44956550383;56990.14514782199;57031.42968570427;57275.42724572868;58006.12990805057;17246.482605521855;17341.82658173402;17467.737983929997;17586.296548137932;17711.734323984892;19149.90425047866;19216.519587009876;19406.611867762847;19558.804411955698;19783.109760060543;15505.22473876279;15618.375264989274;15689.215539223222;15863.127527986071;15897.920510397375;14898.180600066667;14962.177080260255;15130.319135638703;15281.235938203268;15558.222088895736;16145.354185832435;16464.341426342813;16502;16503.66992660164;16623.418180363627;11779.823302650295;11850.466728997388;12057.397130143283;12139.453724582238;12367.196132251374;12395;12644;12762.553310634083;12965.416556254802;13054.934725326693 09:35:30 INFO - PID 29156 | __end_tp_report 09:35:30 INFO - PID 29156 | __start_cc_report 09:35:30 INFO - PID 29156 | _x_x_mozilla_cycle_collect,355 09:35:30 INFO - PID 29156 | __end_cc_report 09:35:30 INFO - PID 29156 | __startTimestamp1504283730246__endTimestamp 09:35:30 INFO - PID 29156 | 09:35:30 INFO - PID 29156 | ------- Summary: start ------- 09:35:30 INFO - PID 29156 | Number of tests: 6 09:35:30 INFO - PID 29156 | 09:35:30 INFO - PID 29156 | [#0] dojo.html Cycles:165 Average:6243.46 Median:6525.74 stddev:734.98 (11.3%) stddev-sans-first:699.63 09:35:30 INFO - PID 29156 | Values: 3284.9 3301.8 3310.9 3313.7 3356.5 6276.9 6443.0 6453.7 6460.2 6497.4 6209.2 6273.2 6284.6 6307.1 6311.1 5666.1 5672.3 5674.1 5692.9 5729.1 5777.3 5802.4 5893.3 5957.1 5983.9 5970.3 5988.1 5993.9 6004.0 6061.5 5694.7 5845.4 5899.4 5924.9 5988.9 5290.0 5734.1 5746.8 5861.9 5882.9 6283.2 6319.0 6343.7 6428.7 6478.3 6549.5 6661.2 6662.1 6861.7 6867.7 6609.3 6664.3 6667.3 6692.2 6694.4 6152.8 6205.6 6209.9 6210.9 6214.4 6815.2 6826.4 6830.6 6831.4 6841.9 6470.0 6559.6 6560.4 6560.8 6566.4 6607.3 6616.2 6626.0 6662.5 6887.8 6809.3 6839.1 6917.8 6931.2 6941.9 6571.6 6839.5 6855.0 6938.3 6957.3 5323.3 5331.1 5342.4 5344.5 5348.0 4483.7 4662.6 4683.6 4721.0 4785.7 5784.7 5821.4 5842.0 5864.0 6071.9 6697.3 6700.8 6724.8 6727.7 6750.6 5746.7 5869.0 5909.0 5913.7 5921.9 5735.9 5809.9 5816.2 5822.7 5852.9 6657.1 6680.6 6725.7 6763.7 6768.8 6573.2 6691.8 6834.4 6881.0 6988.1 6602.5 6605.8 6622.0 6663.7 6666.0 6840.3 6841.7 6851.7 6874.9 6876.9 6355.3 6896.7 6982.6 6988.9 7000.4 6626.0 6675.5 6731.0 6784.1 6788.6 6425.4 6592.0 6625.5 6672.4 6691.3 6455.1 6567.5 6638.0 6654.4 6664.1 6360.4 6587.3 6659.1 6686.7 6711.1 6423.2 6445.4 6525.7 6529.3 6549.5 09:35:30 INFO - PID 29156 | 09:35:30 INFO - PID 29156 | [#1] ext.html Cycles:180 Average:38212.64 Median:35109.74 stddev:55027.36 (156.7%) stddev-sans-first:55109.94 09:35:30 INFO - PID 29156 | Values: 779.5 832.9 858.2 883.4 885.0 38980.0 40231.2 40254.4 40468.6 40543.4 36970.3 37350.1 37534.0 37594.2 37748.8 6885.6 6996.7 6999.1 7012.9 7013.1 6823.6 6832.9 6900.3 6925.5 6928.8 6891.8 7404.6 7586.2 7597.3 7607.5 7473.5 7488.4 7534.7 7543.6 7598.6 7451.4 7484.4 7487.9 7507.2 7512.5 35138.0 36352.8 37404.8 37425.3 37500.0 3483.7 3490.1 3521.6 3522.8 3531.9 31629.8 31701.4 31881.7 31909.0 31911.8 5105.3 5110.1 5121.0 5163.5 5237.3 189706.0 192591.0 196302.0 196599.0 196724.0 46967.1 47114.3 47399.0 47426.3 47489.3 48952.0 49078.5 49140.8 49148.3 49292.8 14006.9 14184.3 14291.4 14296.1 14315.9 11461.8 11494.4 11507.7 11510.9 11512.3 13628.1 13803.8 13846.2 13859.5 13971.8 5581.7 5696.3 5736.7 5833.3 5840.0 989.3 1002.2 1003.7 1005.1 1011.9 23548.6 29038.9 29385.9 30181.8 30246.9 1089.6 1089.8 1094.5 1101.5 1104.1 12388.0 12615.3 12621.0 12635.1 12694.7 230226.0 233652.0 234627.0 234678.0 234829.0 42569.8 49165.5 49348.8 49555.8 49626.8 1273.4 1278.6 1427.4 1436.1 1441.8 27929.0 28106.6 28540.6 28628.0 28838.1 49042.3 50080.5 50283.5 50330.2 50388.5 206271.0 206391.0 206409.0 206442.0 210770.0 34361.3 34710.0 34769.8 35081.5 35140.6 42212.4 43652.8 43950.6 44056.3 44078.3 32495.5 33072.7 33208.7 33285.2 33400.0 31562.2 32352.5 32419.8 32980.0 33014.8 34910.0 36061.3 36396.3 36423.5 36801.6 31346.5 31797.5 32205.7 32372.8 32440.5 22531.7 23465.2 23817.8 24248.5 24364.6 09:35:30 INFO - PID 29156 | 09:35:30 INFO - PID 29156 | [#2] jquery.html Cycles:180 Average:34844.07 Median:2359.08 stddev:79427.51 (3366.9%) stddev-sans-first:79608.59 09:35:30 INFO - PID 29156 | Values: 553.0 555.6 564.7 565.2 570.6 21306.0 22057.2 22288.4 22300.4 22342.1 25730.2 26149.9 26158.7 26196.1 26425.5 6463.5 6604.9 6606.7 6613.8 6622.3 6392.4 6491.1 6528.5 6691.9 6705.9 6586.0 6643.3 6652.3 6652.6 6655.3 6630.2 6641.9 6642.0 6644.2 6646.4 5984.2 6076.3 6087.7 6145.8 6193.7 28390.9 29086.0 29347.1 29678.7 29978.6 1947.9 1979.0 1980.8 1982.5 1985.2 19342.5 19968.8 19983.9 19992.2 20002.4 2343.1 2375.1 2376.9 2400.3 2461.8 132327.3 134996.0 136892.0 137204.0 137672.3 14423.7 14469.5 14744.8 14914.7 15195.9 22820.9 22864.0 22890.9 22953.5 23007.1 9486.8 9763.6 9796.2 9802.8 9811.8 8906.7 8928.2 8990.7 9002.3 9040.1 8375.0 8880.3 9167.2 9244.8 9612.5 2609.2 2623.9 2642.7 2703.8 2729.6 922.4 983.8 988.1 991.5 992.5 19338.4 19978.8 20358.4 20360.1 20646.5 1044.1 1044.2 1057.9 1059.1 1071.0 10952.2 11276.8 11321.9 11345.3 11433.7 457935.0 462499.0 465209.0 471956.0 477685.0 114951.0 127033.0 130910.0 131998.0 132119.0 1215.2 1245.0 1255.5 1263.7 1266.2 20746.0 20887.0 21082.6 21382.6 21600.2 59607.4 60365.0 61292.1 61430.4 62340.0 76334.2 76503.0 76673.6 77073.2 77397.6 14128.5 14864.8 14913.2 15027.3 15125.2 23936.0 24011.0 24278.0 24437.9 24694.0 18322.0 18573.0 18788.5 18792.7 19062.0 16992.8 17044.8 17243.5 17273.7 17602.7 19018.3 19323.7 19459.3 19706.3 19891.0 14095.0 14831.8 14835.2 14889.4 14939.6 17308.1 18264.8 18698.5 18814.5 18858.1 09:35:30 INFO - PID 29156 | 09:35:30 INFO - PID 29156 | [#3] mootools.html Cycles:180 Average:9238.48 Median:14461.98 stddev:6529.96 (45.2%) stddev-sans-first:6521.18 09:35:30 INFO - PID 29156 | Values: 1320.7 1373.9 1377.8 1380.3 1393.1 14728.6 15268.1 15284.9 15305.8 15397.8 14806.9 15790.7 15864.4 15873.8 15895.8 2672.5 2726.3 2727.1 2728.4 2758.1 2592.1 2691.9 2697.8 2697.8 2706.1 2652.2 2669.6 2691.5 2692.7 2708.3 2574.9 2579.9 2598.3 2607.0 2609.7 2582.9 2597.6 2605.8 2606.9 2616.1 14310.7 14466.4 14702.6 14868.9 14871.4 7941.4 8138.4 8142.7 8160.0 8200.0 5441.5 5573.4 5708.5 5747.1 5779.7 1910.4 1923.1 1957.5 2015.0 2018.3 25520.6 26048.7 26307.9 26452.2 27223.2 6703.7 6831.1 6934.8 7063.9 7082.5 16921.8 16936.7 16973.0 16973.8 16997.9 7809.6 7961.4 7984.7 7992.6 8004.4 7299.6 7324.5 7426.1 7445.1 7563.4 5186.6 5346.8 5347.9 5378.5 5399.5 1483.9 1513.1 1535.1 1576.4 1588.3 891.6 922.4 926.2 934.3 943.3 8245.4 8427.7 8463.3 8465.4 8481.5 959.4 990.8 993.3 993.8 997.1 5466.6 5550.8 5623.5 5652.9 5833.9 20926.9 21225.2 21264.0 21930.6 22130.8 20379.2 20895.5 21323.3 21396.6 21599.6 9312.3 9597.1 10727.8 10728.5 10924.3 7683.1 8564.9 8648.1 8672.0 8801.7 17011.1 17547.8 17615.4 17650.8 17809.3 17927.8 17951.2 18128.0 18223.4 18404.5 10906.9 10978.5 11033.0 11155.4 11229.2 13591.7 13829.2 13834.7 13839.6 13915.1 11640.5 11680.2 11734.2 11833.8 12082.3 11233.3 11288.5 11515.5 11574.4 11703.2 9142.5 9199.3 9207.0 9213.0 9214.2 7001.5 7060.0 7132.3 7166.6 7172.4 6760.0 6905.5 6929.2 6989.8 6999.8 09:35:30 INFO - PID 29156 | 09:35:30 INFO - PID 29156 | [#4] prototype.html Cycles:180 Average:5632.74 Median:6274.14 stddev:1601.93 (25.5%) stddev-sans-first:1559.14 09:35:30 INFO - PID 29156 | Values: 485.2 490.8 500.2 508.8 524.9 6324.3 6387.3 6404.2 6443.5 6460.5 5972.0 6169.2 6192.4 6215.3 6242.8 5010.3 5048.0 5059.3 5073.8 5103.6 4878.1 5029.3 5044.0 5084.3 5108.6 5025.6 5041.1 5087.3 5088.7 5091.9 4999.7 5027.3 5041.1 5043.6 5082.5 4490.1 4499.3 4528.5 4534.9 4543.2 6272.5 6275.8 6287.2 6327.6 6372.8 5817.2 5826.4 5842.6 5846.2 5895.7 6620.4 6664.7 6698.2 6710.8 6735.8 4854.3 4860.1 4939.3 4939.8 4963.9 7025.1 7025.9 7026.4 7049.9 7135.6 5079.8 5121.2 5123.4 5125.9 5151.6 5171.8 5180.4 5183.8 5184.8 5209.7 5699.7 5714.3 5736.8 5786.7 5813.2 6655.7 6732.8 6800.7 6801.0 6805.8 1642.3 1675.9 1697.2 1721.4 1767.0 1211.4 1220.6 1228.1 1241.5 1254.2 5871.8 5905.7 5941.7 5983.3 5993.7 6720.6 6783.9 6800.6 6823.5 6823.6 5765.3 5924.4 5948.0 5948.2 5951.8 3632.7 3640.7 3689.4 3706.3 3707.3 6655.0 6677.6 6696.4 6711.4 6814.6 7013.4 7076.6 7090.7 7113.3 7155.0 6671.5 6680.6 6725.0 6798.8 6869.9 6922.2 6941.2 6991.3 7022.6 7035.5 6753.3 6810.4 6822.6 6889.2 6976.0 6663.8 6669.7 6694.4 6734.8 6744.0 4992.2 5060.0 5079.7 5086.4 5099.6 6970.9 7024.3 7046.9 7053.4 7067.9 6520.7 6606.0 6664.5 6737.3 6774.3 6614.7 6617.3 6651.4 6681.7 6694.2 6404.7 6527.3 6642.4 6644.3 6720.6 6282.8 6638.7 6642.6 6674.2 6702.4 6387.8 6423.7 6453.0 6509.5 6574.2 09:35:30 INFO - PID 29156 | 09:35:30 INFO - PID 29156 | [#5] yui.html Cycles:180 Average:23381.05 Median:19311.57 stddev:33294.08 (172.4%) stddev-sans-first:33344.60 09:35:30 INFO - PID 29156 | Values: 878.1 892.2 903.0 907.3 924.7 17744.9 18575.9 18659.3 18922.8 18925.3 13375.9 13381.3 16228.5 20029.3 20211.5 981.1 999.9 1000.3 1002.3 1002.8 951.0 987.2 993.0 993.4 995.7 1034.2 1034.5 1041.0 1045.7 1048.1 1036.8 1039.7 1039.8 1043.2 1044.0 754.4 763.8 766.1 769.0 770.5 19598.0 19837.8 19862.9 19958.4 20087.6 20465.8 20580.7 21143.6 21262.1 21282.8 16587.7 17074.7 17111.3 17220.7 17265.5 3716.7 3729.8 3740.7 3743.1 3747.0 131369.0 131746.0 132619.0 133582.3 134531.3 15843.5 15974.1 15997.7 16094.6 16231.8 28192.6 28486.7 28519.7 29316.0 29883.0 7903.2 8007.8 8051.0 8077.1 8118.4 3998.8 4014.1 4114.6 4132.8 4233.8 5027.0 5137.0 5146.6 5152.0 5201.7 1033.8 1037.4 1075.3 1083.1 1086.3 670.7 690.1 695.6 703.1 703.4 10845.1 11208.7 11313.7 11461.3 11676.1 678.8 691.4 695.5 708.3 709.0 5331.2 5453.6 5465.1 5468.4 5506.3 113709.4 115442.0 117815.0 119844.0 120900.4 95229.0 95940.5 100958.5 103494.0 106511.0 85902.8 87339.0 89262.0 89707.1 91440.5 10683.7 10935.7 10999.7 11647.2 11987.0 40084.6 40698.4 41222.4 41881.4 42070.0 55043.4 56990.1 57031.4 57275.4 58006.1 17246.5 17341.8 17467.7 17586.3 17711.7 19149.9 19216.5 19406.6 19558.8 19783.1 15505.2 15618.4 15689.2 15863.1 15897.9 14898.2 14962.2 15130.3 15281.2 15558.2 16145.4 16464.3 16502.0 16503.7 16623.4 11779.8 11850.5 12057.4 12139.5 12367.2 12395.0 12644.0 12762.6 12965.4 13054.9 09:35:30 INFO - PID 29156 | -------- Summary: end -------- 09:35:30 INFO - PID 29156 | 09:35:31 INFO - TEST-INFO | 29156: exit 0 09:35:31 INFO - TEST-OK | dromaeo_css | took 1113538ms 09:35:31 INFO - TEST-START | kraken 09:35:31 INFO - Initialising browser for kraken test... 09:35:31 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56209/getInfo.html -profile /tmp/tmpZWTYKn/profile 09:35:31 INFO - TEST-INFO | started process 29533 (/builds/slave/test/build/application/firefox/firefox http://localhost:56209/getInfo.html) 09:35:37 INFO - TEST-INFO | 29533: exit 0 09:35:37 INFO - Browser initialized. 09:35:37 INFO - Running cycle 1/1 for kraken test... 09:35:37 INFO - Using env: {'DISPLAY': ':0', 09:35:37 INFO - 'HOME': '/home/cltbld', 09:35:37 INFO - 'JSGC_DISABLE_POISONING': '1', 09:35:37 INFO - 'LANG': 'en_US.UTF-8', 09:35:37 INFO - 'LANGUAGE': 'en_US:en', 09:35:37 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 09:35:37 INFO - 'LOGNAME': 'cltbld', 09:35:37 INFO - 'MAIL': '/var/mail/cltbld', 09:35:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:35:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:35:37 INFO - 'MOZ_ACCELERATED': '1', 09:35:37 INFO - 'MOZ_CRASHREPORTER': '1', 09:35:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:37 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 09:35:37 INFO - 'MOZ_NO_REMOTE': '1', 09:35:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:35:37 INFO - 'MOZ_WEBRENDER': '1', 09:35:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:37 INFO - 'NO_EM_RESTART': '1', 09:35:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:37 INFO - 'PWD': '/builds/slave/test', 09:35:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:35:37 INFO - 'RUST_BACKTRACE': 'full', 09:35:37 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:35:37 INFO - 'SHELL': '/bin/bash', 09:35:37 INFO - 'SHLVL': '1', 09:35:37 INFO - 'TERM': 'linux', 09:35:37 INFO - 'TMOUT': '86400', 09:35:37 INFO - 'USER': 'cltbld', 09:35:37 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027', 09:35:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:37 INFO - '_': '/tools/buildbot/bin/python'} 09:35:37 INFO - TEST-INFO | started process 29726 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZWTYKn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:35:38 INFO - PID 29726 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 09:35:38 INFO - PID 29726 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 09:35:38 INFO - PID 29726 | 09:35:38 INFO - PID 29726 | (/builds/slave/test/build/application/firefox/firefox:29787): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:35:38 INFO - PID 29726 | 09:35:49 INFO - PID 29726 | RSS: Main: 205418496 09:35:49 INFO - PID 29726 | 09:35:51 INFO - PID 29726 | Cycle 1(1): loaded http://localhost:56209/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56209/tests/kraken/driver.html?testName=audio-beat-detection) 09:35:52 INFO - PID 29726 | RSS: Main: 211152896 09:35:52 INFO - PID 29726 | 09:35:53 INFO - PID 29726 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 09:35:55 INFO - PID 29726 | Cycle 1(1): loaded http://localhost:56209/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56209/tests/kraken/driver.html?testName=audio-dft) 09:35:56 INFO - PID 29726 | RSS: Main: 216440832 09:35:56 INFO - PID 29726 | 09:35:59 INFO - PID 29726 | Cycle 1(1): loaded http://localhost:56209/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56209/tests/kraken/driver.html?testName=audio-fft) 09:35:59 INFO - PID 29726 | RSS: Main: 210399232 09:35:59 INFO - PID 29726 | 09:36:01 INFO - PID 29726 | Cycle 1(1): loaded http://localhost:56209/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56209/tests/kraken/driver.html?testName=audio-oscillator) 09:36:02 INFO - PID 29726 | RSS: Main: 211054592 09:36:02 INFO - PID 29726 | 09:36:04 INFO - PID 29726 | Cycle 1(1): loaded http://localhost:56209/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56209/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:36:05 INFO - PID 29726 | RSS: Main: 210300928 09:36:05 INFO - PID 29726 | 09:36:08 INFO - PID 29726 | Cycle 1(1): loaded http://localhost:56209/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56209/tests/kraken/driver.html?testName=imaging-darkroom) 09:36:09 INFO - PID 29726 | RSS: Main: 210894848 09:36:09 INFO - PID 29726 | 09:36:12 INFO - PID 29726 | Cycle 1(1): loaded http://localhost:56209/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56209/tests/kraken/driver.html?testName=imaging-desaturate) 09:36:13 INFO - PID 29726 | RSS: Main: 211525632 09:36:13 INFO - PID 29726 | 09:36:16 INFO - PID 29726 | Cycle 1(1): loaded http://localhost:56209/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56209/tests/kraken/driver.html?testName=json-parse-financial) 09:36:16 INFO - PID 29726 | RSS: Main: 212766720 09:36:16 INFO - PID 29726 | 09:36:18 INFO - PID 29726 | Cycle 1(1): loaded http://localhost:56209/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56209/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:36:19 INFO - PID 29726 | RSS: Main: 213114880 09:36:19 INFO - PID 29726 | 09:36:20 INFO - PID 29726 | Cycle 1(1): loaded http://localhost:56209/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56209/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:36:21 INFO - PID 29726 | RSS: Main: 212516864 09:36:21 INFO - PID 29726 | 09:36:23 INFO - PID 29726 | Cycle 1(1): loaded http://localhost:56209/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56209/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:36:24 INFO - PID 29726 | RSS: Main: 212148224 09:36:24 INFO - PID 29726 | 09:36:27 INFO - PID 29726 | Cycle 1(1): loaded http://localhost:56209/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56209/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:36:27 INFO - PID 29726 | RSS: Main: 212856832 09:36:27 INFO - PID 29726 | 09:36:30 INFO - PID 29726 | Cycle 1(1): loaded http://localhost:56209/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56209/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:36:31 INFO - PID 29726 | RSS: Main: 213270528 09:36:31 INFO - PID 29726 | 09:36:33 INFO - PID 29726 | Cycle 1(1): loaded http://localhost:56209/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56209/tests/kraken/driver.html?testName=ai-astar) 09:36:33 INFO - PID 29726 | RSS: Main: 215789568 09:36:33 INFO - PID 29726 | 09:36:33 INFO - PID 29726 | __start_tp_report 09:36:33 INFO - PID 29726 | _x_x_mozilla_page_load 09:36:33 INFO - PID 29726 | _x_x_mozilla_page_load_details 09:36:33 INFO - PID 29726 | |i|pagename|runs| 09:36:33 INFO - PID 29726 | |0;ai-astar;98;109;101;109;108;109;108;101;117;116 09:36:33 INFO - PID 29726 | |1;audio-beat-detection;152;117;146;186;128;163;147;120;178;118 09:36:33 INFO - PID 29726 | |2;audio-dft;163;170;164;161;163;166;167;176;167;164 09:36:33 INFO - PID 29726 | |3;audio-fft;73;74;81;191;75;73;76;74;171;87 09:36:33 INFO - PID 29726 | |4;audio-oscillator;79;80;130;83;80;80;126;78;80;127 09:36:33 INFO - PID 29726 | |5;imaging-gaussian-blur;114;115;125;114;114;115;122;116;115;118 09:36:33 INFO - PID 29726 | |6;imaging-darkroom;151;158;158;156;149;150;152;150;148;150 09:36:33 INFO - PID 29726 | |7;imaging-desaturate;103;98;94;95;102;95;98;95;94;95 09:36:33 INFO - PID 29726 | |8;json-parse-financial;60;58;54;54;65;61;55;54;58;54 09:36:33 INFO - PID 29726 | |9;json-stringify-tinderbox;46;40;38;48;46;42;42;45;38;52 09:36:33 INFO - PID 29726 | |10;stanford-crypto-aes;89;75;84;82;82;78;85;78;81;79 09:36:33 INFO - PID 29726 | |11;stanford-crypto-ccm;122;123;123;120;121;121;126;127;118;164 09:36:33 INFO - PID 29726 | |12;stanford-crypto-pbkdf2;174;178;174;180;184;180;173;173;176;177 09:36:33 INFO - PID 29726 | |13;stanford-crypto-sha256-iterative;65;68;65;68;71;69;64;67;64;65 09:36:33 INFO - PID 29726 | __end_tp_report 09:36:33 INFO - PID 29726 | __start_cc_report 09:36:33 INFO - PID 29726 | _x_x_mozilla_cycle_collect,919 09:36:33 INFO - PID 29726 | __end_cc_report 09:36:33 INFO - PID 29726 | __startTimestamp1504283793578__endTimestamp 09:36:33 INFO - PID 29726 | 09:36:33 INFO - PID 29726 | ------- Summary: start ------- 09:36:33 INFO - PID 29726 | Number of tests: 14 09:36:33 INFO - PID 29726 | 09:36:33 INFO - PID 29726 | [#0] ai-astar Cycles:10 Average:107.60 Median:109.00 stddev:6.19 (5.7%) stddev-sans-first:5.50 09:36:33 INFO - PID 29726 | Values: 98.0 109.0 101.0 109.0 108.0 109.0 108.0 101.0 117.0 116.0 09:36:33 INFO - PID 29726 | 09:36:33 INFO - PID 29726 | [#1] audio-beat-detection Cycles:10 Average:145.50 Median:149.50 stddev:24.88 (16.6%) stddev-sans-first:26.28 09:36:33 INFO - PID 29726 | Values: 152.0 117.0 146.0 186.0 128.0 163.0 147.0 120.0 178.0 118.0 09:36:33 INFO - PID 29726 | 09:36:33 INFO - PID 29726 | [#2] audio-dft Cycles:10 Average:166.10 Median:166.50 stddev:4.33 (2.6%) stddev-sans-first:4.45 09:36:33 INFO - PID 29726 | Values: 163.0 170.0 164.0 161.0 163.0 166.0 167.0 176.0 167.0 164.0 09:36:33 INFO - PID 29726 | 09:36:33 INFO - PID 29726 | [#3] audio-fft Cycles:10 Average:97.50 Median:74.50 stddev:44.47 (59.7%) stddev-sans-first:46.28 09:36:33 INFO - PID 29726 | Values: 73.0 74.0 81.0 191.0 75.0 73.0 76.0 74.0 171.0 87.0 09:36:33 INFO - PID 29726 | 09:36:33 INFO - PID 29726 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:80.00 stddev:23.08 (28.8%) stddev-sans-first:23.81 09:36:33 INFO - PID 29726 | Values: 79.0 80.0 130.0 83.0 80.0 80.0 126.0 78.0 80.0 127.0 09:36:33 INFO - PID 29726 | 09:36:33 INFO - PID 29726 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:115.50 stddev:3.79 (3.3%) stddev-sans-first:3.89 09:36:33 INFO - PID 29726 | Values: 114.0 115.0 125.0 114.0 114.0 115.0 122.0 116.0 115.0 118.0 09:36:33 INFO - PID 29726 | 09:36:33 INFO - PID 29726 | [#6] imaging-darkroom Cycles:10 Average:152.20 Median:151.50 stddev:3.74 (2.5%) stddev-sans-first:3.94 09:36:33 INFO - PID 29726 | Values: 151.0 158.0 158.0 156.0 149.0 150.0 152.0 150.0 148.0 150.0 09:36:33 INFO - PID 29726 | 09:36:33 INFO - PID 29726 | [#7] imaging-desaturate Cycles:10 Average:96.90 Median:95.00 stddev:3.28 (3.5%) stddev-sans-first:2.64 09:36:33 INFO - PID 29726 | Values: 103.0 98.0 94.0 95.0 102.0 95.0 98.0 95.0 94.0 95.0 09:36:33 INFO - PID 29726 | 09:36:33 INFO - PID 29726 | [#8] json-parse-financial Cycles:10 Average:57.30 Median:58.00 stddev:3.80 (6.6%) stddev-sans-first:3.91 09:36:33 INFO - PID 29726 | Values: 60.0 58.0 54.0 54.0 65.0 61.0 55.0 54.0 58.0 54.0 09:36:33 INFO - PID 29726 | 09:36:33 INFO - PID 29726 | [#9] json-stringify-tinderbox Cycles:10 Average:43.70 Median:45.50 stddev:4.52 (9.9%) stddev-sans-first:4.72 09:36:33 INFO - PID 29726 | Values: 46.0 40.0 38.0 48.0 46.0 42.0 42.0 45.0 38.0 52.0 09:36:33 INFO - PID 29726 | 09:36:33 INFO - PID 29726 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:82.00 stddev:4.06 (4.9%) stddev-sans-first:3.21 09:36:33 INFO - PID 29726 | Values: 89.0 75.0 84.0 82.0 82.0 78.0 85.0 78.0 81.0 79.0 09:36:33 INFO - PID 29726 | 09:36:33 INFO - PID 29726 | [#11] stanford-crypto-ccm Cycles:10 Average:126.50 Median:123.00 stddev:13.44 (10.9%) stddev-sans-first:14.16 09:36:33 INFO - PID 29726 | Values: 122.0 123.0 123.0 120.0 121.0 121.0 126.0 127.0 118.0 164.0 09:36:33 INFO - PID 29726 | 09:36:33 INFO - PID 29726 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.90 Median:177.50 stddev:3.63 (2.0%) stddev-sans-first:3.70 09:36:33 INFO - PID 29726 | Values: 174.0 178.0 174.0 180.0 184.0 180.0 173.0 173.0 176.0 177.0 09:36:33 INFO - PID 29726 | 09:36:33 INFO - PID 29726 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.50 stddev:2.37 (3.5%) stddev-sans-first:2.44 09:36:33 INFO - PID 29726 | Values: 65.0 68.0 65.0 68.0 71.0 69.0 64.0 67.0 64.0 65.0 09:36:33 INFO - PID 29726 | -------- Summary: end -------- 09:36:33 INFO - PID 29726 | 09:36:34 INFO - PID 29726 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 09:36:34 INFO - TEST-INFO | 29726: exit 0 09:36:34 INFO - TEST-OK | kraken | took 63268ms 09:36:34 INFO - SUITE-END | took 1176s 09:36:34 INFO - Completed test suite (00:19:36) 09:36:34 INFO - javascript benchmark 09:36:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8544.265642674673, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3284.9343013139733, 3301.7523685723577, 3310.9172270693243, 3313.718333941614, 3356.546866173066, 6276.874462510747, 6443.001334793109, 6453.741850325993, 6460.192475940507, 6497.415232629062, 6209.223847019122, 6273.2472103347545, 6284.5600807943465, 6307.117003619485, 6311.116443697874, 5666.093425051992, 5672.290963629546, 5674.148877668342, 5692.857678558028, 5729.140628905666, 5777.306723193223, 5802.448767367094, 5893.263342082239, 5957.136215248786, 5983.880322393549, 5970.34326224115, 5988.071848863411, 5993.850153746148, 6003.969980150093, 6061.54538409621, 5694.743736531866, 5845.357010728816, 5899.439553242435, 5924.940750592504, 5988.9101663475085, 5290.0, 5734.111212762028, 5746.827595172152, 5861.85345366365, 5882.882342353172, 6283.214598175228, 6319.020551814476, 6343.746250149989, 6428.678566071724, 6478.255000674889, 6549.508786841006, 6661.200655921272, 6662.067310576524, 6861.725530978755, 6867.656617169122, 6609.272979972199, 6664.300248473937, 6667.266600673922, 6692.230393504712, 6694.364035416627, 6152.78465253714, 6205.596636218676, 6209.875802483925, 6210.937890621126, 6214.40963108504, 6815.2162501312, 6826.385625293748, 6830.590164590041, 6831.351021652935, 6841.931580684229, 6469.967650161719, 6559.6064236145985, 6560.442362399154, 6560.770373037016, 6566.409023187936, 6607.306232845578, 6616.206055273398, 6626.0, 6662.467002639777, 6887.758928437481, 6809.285025072296, 6839.110915580943, 6917.827054323682, 6931.1682598088555, 6941.930580694229, 6571.57284776488, 6839.452843772487, 6855.040294358794, 6938.306169383122, 6957.339052789976, 5323.3079699639575, 5331.147016477423, 5342.439043900412, 5344.465553444625, 5348.037353276448, 4483.686141970096, 4662.626989840873, 4683.625309975194, 4721.032188401438, 4785.665003449725, 5784.739686714108, 5821.446962538552, 5842.0, 5864.0, 6071.878562428727, 6697.296783837724, 6700.765473208415, 6724.764633237911, 6727.697253623599, 6750.594964302158, 5746.741396637161, 5869.031609784423, 5909.025010873243, 5913.704314784243, 5921.940780592225, 5735.8566035849435, 5809.941900581025, 5816.185733997244, 5822.737976791054, 5852.941470585325, 6657.101291325609, 6680.63256520896, 6725.6973436195485, 6763.695633696496, 6768.830779230559, 6573.211214654081, 6691.799246022627, 6834.419074378733, 6881.0, 6988.091548098715, 6602.471802255904, 6605.834854128685, 6622.0, 6663.733450661919, 6665.966775149859, 6840.31596840312, 6841.657917104026, 6851.725930962705, 6874.896876546756, 6876.931230687829, 6355.30091689923, 6896.724131034703, 6982.615956122462, 6988.860222795617, 7000.439964802906, 6626.0, 6675.499337549606, 6730.966345168243, 6784.08414863987, 6788.558743681645, 6425.4217120457515, 6592.0, 6625.536212465081, 6672.432843208382, 6691.297413771678, 6455.128557644658, 6567.540272180901, 6638.0, 6654.434373078149, 6664.0670306158145, 6360.363963603602, 6587.308332625005, 6659.067730517828, 6686.732530698718, 6711.060451536691, 6423.164988551458, 6445.387688169616, 6525.738970441129, 6529.347065293433, 6549.508786841101], "value": 6190.014433833346, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [779.4504874063786, 832.9333653307737, 858.1761508951406, 883.3507372081523, 884.9999999999998, 38980.0, 40231.195376092495, 40254.39618405722, 40468.59531404685, 40543.39184912229, 36970.26059478809, 37350.06624834381, 37534.0, 37594.24811503775, 37748.811255943685, 6885.552439091455, 6996.6851491683, 6999.12510936133, 7012.948057791321, 7013.08829852121, 6823.590584564942, 6832.863342733142, 6900.344467275626, 6925.480588955823, 6928.757493487729, 6891.758788442406, 7404.592747398891, 7586.241375862425, 7597.278258565455, 7607.505512141721, 7473.51422157558, 7488.427430239656, 7534.660940257701, 7543.585102819343, 7598.620068996528, 7451.366633836131, 7484.438667099989, 7487.887681684779, 7507.249275072449, 7512.5492470451945, 35138.0, 36352.81823590892, 37404.812975935216, 37425.25149496995, 37500.0, 3483.738719596015, 3490.057684425191, 3521.559805024372, 3522.84147213376, 3531.858725650971, 31629.841850790828, 31701.365972680418, 31881.681183188102, 31909.0, 31911.840440797878, 5105.336306280197, 5110.080185566597, 5121.0, 5163.457836927144, 5237.292965449654, 189706.0, 192591.0, 196302.0, 196599.0, 196724.0163799186, 46967.060658786635, 47114.29328560075, 47399.0, 47426.28860567094, 47489.28766068512, 48952.0, 49078.5092149074, 49140.754296229, 49148.262776058385, 49292.753536232805, 14006.859931400759, 14184.290785460686, 14291.356888940023, 14296.070755400866, 14315.928420357832, 11461.770764584662, 11494.42527873603, 11507.71230719222, 11510.884891150981, 11512.251703639236, 13628.11417257895, 13803.792943105862, 13846.169229846242, 13859.514916977858, 13971.79042314366, 5581.665100094007, 5696.259486266758, 5736.741846616912, 5833.270841144857, 5839.970800146057, 989.2531138990016, 1002.2132625888643, 1003.7239759066169, 1005.0602686488157, 1011.8937511561327, 23548.64677029846, 29038.854805725838, 29385.85307073451, 30181.849090754844, 30246.941357052398, 1089.6349722842779, 1089.809283375406, 1094.5293523784849, 1101.4823033174396, 1104.089126470665, 12388.0, 12615.30615816139, 12621.0, 12635.115541911979, 12694.682632934251, 230226.0, 233652.0, 234627.0, 234678.0, 234829.0, 42569.787151064666, 49165.508344916096, 49348.75325623349, 49555.75222123866, 49626.75186624044, 1273.40150129439, 1278.571678487717, 1427.421894132878, 1436.080908218741, 1441.7621092519616, 27929.0, 28106.578401323586, 28540.57189142123, 28628.0, 28838.134855954355, 49042.264366035255, 50080.49919500904, 50283.497165029345, 50330.2450463236, 50388.49611503838, 206271.0, 206391.0, 206409.0, 206442.0, 210770.0, 34361.31277374489, 34710.0, 34769.826150869085, 35081.47377789284, 35140.64859351374, 42212.366814497196, 43652.781736091114, 43950.560494395926, 44056.339154912064, 44078.33882491701, 32495.512567311984, 33072.66927330792, 33208.66791332152, 33285.16787080342, 33400.0, 31562.210944725648, 32352.514712278862, 32419.837900810344, 32980.0, 33014.83492582522, 34910.0, 36061.27877442384, 36396.27207455783, 36423.45364819583, 36801.63198367982, 31346.52980205253, 31797.523037154922, 32205.677943221206, 32372.838135810114, 32440.513392299606, 22531.66202506931, 23465.178718744763, 23817.761822382246, 24248.51502969966, 24364.634530481704], "value": 17117.270936499914, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.9668219906805, 555.6388347574076, 564.7176411794105, 565.2115299157674, 570.5863249144368, 21306.0, 22057.22799702011, 22288.442788930264, 22300.442488937744, 22342.10631574739, 25730.228093157188, 26149.869250653766, 26158.73841261587, 26196.083137090205, 26425.471490570177, 6463.515236357281, 6604.900926486107, 6606.735730570771, 6613.801585952405, 6622.337766223363, 6392.360763923617, 6491.091247225392, 6528.543001989862, 6691.866162676743, 6705.899411508832, 6586.0, 6643.269240383554, 6652.268250492442, 6652.567583107082, 6655.267920528762, 6630.171228596426, 6641.900371494431, 6641.966790166042, 6644.169478815148, 6646.401823835829, 5984.162217289583, 6076.301225359096, 6087.726052327655, 6145.78489752861, 6193.659348735818, 28390.85804570964, 29086.0, 29347.119586412442, 29678.70321296781, 29978.55032174519, 1947.9123439445118, 1979.0302751651586, 1980.8316293115101, 1982.5142840004148, 1985.176151896961, 19342.516437089045, 19968.800311996984, 19983.90008049965, 19992.20031198765, 20002.39992800218, 2343.083249282328, 2375.0855920470744, 2376.918502081549, 2400.291913885408, 2461.815363847719, 132327.33836330756, 134996.0, 136892.0, 137204.0, 137672.31163844216, 14423.711525769426, 14469.49356772508, 14744.778828317583, 14914.70170596582, 15195.92402038005, 22820.885895570416, 22864.0, 22890.885545572164, 22953.540929181323, 23007.07971681114, 9486.762830929143, 9763.584898138113, 9796.16732577739, 9802.75493112678, 9811.75470613226, 8906.688265910661, 8928.196462318292, 8990.730278091667, 9002.279817614575, 9040.141186587263, 8374.958125209336, 8880.333974951904, 9167.17495425415, 9244.815103697889, 9612.471314077793, 2609.178108895692, 2623.9475210496066, 2642.6828780546457, 2703.837769733783, 2729.5769155780886, 922.4280945813639, 983.7540614846289, 988.1205726903062, 991.5191132300828, 992.4938281476354, 19338.4198474046, 19978.800211997986, 20358.389248322575, 20360.083796229206, 20646.483837904172, 1044.1438020822852, 1044.1907521670614, 1057.9259451838298, 1059.1103473082649, 1070.9892901071046, 10952.178586606036, 11276.774464510663, 11321.94339028316, 11345.262557933707, 11433.656990290305, 457935.0, 462499.0, 465209.0, 471956.0, 477685.0, 114951.0, 127033.0, 130910.0, 131998.0, 132119.0, 1215.2283300104498, 1244.9875501244871, 1255.453877563263, 1263.6524955637274, 1266.2022925557023, 20746.0, 20887.0, 21082.57834843264, 21382.572348553254, 21600.243991460222, 59607.40392596192, 60365.0, 61292.08061878986, 61430.38569614425, 62340.0, 76334.23665763272, 76503.0, 76673.61663191872, 77073.22926770886, 77397.61301193458, 14128.50550230737, 14864.777028344368, 14913.179775112472, 15027.346991830671, 15125.243737812845, 23936.0, 24011.0, 24278.0, 24437.877810610833, 24694.0, 18322.0, 18573.0, 18788.530286742392, 18792.7181092281, 19062.0, 16992.810503264653, 17044.829551704323, 17243.482695519153, 17273.654526909642, 17602.73595896037, 19018.334358297394, 19323.710144347566, 19459.318923837596, 19706.31027914016, 19891.0, 14095.0, 14831.777523336943, 14835.184064876536, 14889.436609155882, 14939.626509336918, 17308.134593270537, 18264.81735182631, 18698.532536686693, 18814.52963675864, 18858.057097144814], "value": 12132.89274597926, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1320.6632308761264, 1373.9106958047726, 1377.7726675098609, 1380.2753554383953, 1393.1153717389457, 14728.6317842054, 15268.083914965096, 15284.923575382107, 15305.84694153058, 15397.846021539795, 14806.851931480682, 15790.684186316266, 15864.444744433948, 15873.84126158741, 15895.841041589581, 2672.4521473098066, 2726.2502811726736, 2727.059164588214, 2728.4133911209087, 2758.0760445250767, 2592.1316359019656, 2691.946161076777, 2697.8381297122137, 2697.8381297122237, 2706.07993282284, 2652.2308530526125, 2669.626252324676, 2691.5020721166547, 2692.6634170728657, 2708.255229811798, 2574.909878154265, 2579.85810780407, 2598.3244356467267, 2607.0, 2609.6607441032734, 2582.883770230344, 2597.5584150694426, 2605.830621009647, 2606.882690278942, 2616.0974463809957, 14310.713785724227, 14466.421343146367, 14702.632434189123, 14868.851311486855, 14871.405143794234, 7941.36469082472, 8138.430309878311, 8142.715004974797, 8160.0, 8199.95900020496, 5441.483059109379, 5573.442655734435, 5708.543316534668, 5747.109198074305, 5779.739911703941, 1910.4173227165709, 1923.0961448119363, 1957.5106223444138, 2014.9899250503654, 2018.3137733170793, 25520.617190742152, 26048.73951260482, 26307.868460657766, 26452.206433807016, 27223.183304500897, 6703.698333575001, 6831.11195544576, 6934.826629334307, 7063.89404158938, 7082.468814838908, 16921.83078169227, 16936.66126677484, 16973.0, 16973.830261697472, 16997.915010425117, 7809.648565814552, 7961.36309095271, 7984.720534781255, 7992.560409177435, 8004.399670024772, 7299.635018249066, 7324.450666200057, 7426.108866935894, 7445.106587209461, 7563.357114645317, 5186.611004174702, 5346.759395827197, 5347.866303342447, 5378.542823859937, 5399.4600539945695, 1483.9109653420828, 1513.137511618367, 1535.1479928639712, 1576.4167258114571, 1588.348777001424, 891.5943245823134, 922.3912217936264, 926.2451102351562, 934.3319526538422, 943.2595412601075, 8245.381596380175, 8427.662893484192, 8463.322934165375, 8465.36509761758, 8481.533515656574, 959.3524371049514, 990.762217067913, 993.279872092727, 993.7962717642865, 997.0627610046533, 5466.562674985991, 5550.750216240279, 5623.493885550321, 5652.943470565324, 5833.88332233353, 20926.895365523076, 21225.15099396038, 21264.0, 21930.561388772134, 22130.778692212873, 20379.18483260653, 20895.477613059797, 21323.253686120912, 21396.57206855854, 21599.56800863974, 9312.301577381588, 9597.136257736836, 10727.83908241377, 10728.517216725266, 10924.344539327665, 7683.116441609286, 8564.929383827022, 8648.135186481302, 8671.956640216758, 8801.735947921572, 17011.149442528073, 17547.82452175513, 17615.383461578782, 17650.823491764917, 17809.287628495233, 17927.820721792614, 17951.192196350934, 18128.0, 18223.362182323555, 18404.539886502946, 10906.945465272624, 10978.505967231335, 11033.0, 11155.386453745123, 11229.157813163882, 13591.660208494866, 13829.170249785046, 13834.65413364674, 13839.584812455643, 13915.095518791246, 11640.476178571815, 11680.182387232811, 11734.237274577124, 11833.763324733629, 12082.275063496218, 11233.269837460539, 11288.492017859051, 11515.53937842477, 11574.363410012193, 11703.180777345504, 9142.542872856464, 9199.264058875407, 9206.9539652304, 9213.0, 9214.170724634547, 7001.50989430735, 7060.0, 7132.322429369202, 7166.641667916479, 7172.390346820579, 6759.966200169164, 6905.482088843457, 6929.237783843874, 6989.790306290819, 6999.755008574879], "value": 6654.567345960256, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.1776239274427, 490.78650786907696, 500.2296463446293, 508.7761384990605, 524.9317588713469, 6324.3043265240785, 6387.297397286301, 6404.1674582304295, 6443.484521238302, 6460.547761656686, 5972.0, 6169.175111430908, 6192.380761923817, 6215.347388524207, 6242.812715618527, 5010.298558201855, 5048.0, 5059.316992206061, 5073.822416215433, 5103.617228707862, 4878.121938051149, 5029.2958985742025, 5044.0, 5084.262781896623, 5108.565771909373, 5025.57282630975, 5041.076175818035, 5087.321184009286, 5088.735512696851, 5091.879786446994, 4999.650024498286, 5027.296178535008, 5041.143005689041, 5043.64694471389, 5082.491750824925, 4490.079533695617, 4499.302608095718, 4528.547145285445, 4534.8866278342975, 4543.182227199103, 6272.498200144023, 6275.780347687854, 6287.2140982377205, 6327.5570710050315, 6372.808815735535, 5817.214676018768, 5826.368616787307, 5842.649441033552, 5846.239988801429, 5895.734691938873, 6620.437262832665, 6664.666766661648, 6698.196216454056, 6710.765123220713, 6735.764248251287, 4854.2961270615915, 4860.149473842063, 4939.283803848458, 4939.753012349368, 4963.875903102451, 7025.121859767529, 7025.929740702579, 7026.402755765766, 7049.894251586179, 7135.571865688075, 5079.822206222764, 5121.206213036985, 5123.410807757081, 5125.948740512622, 5151.5878729701535, 5171.818986335536, 5180.352455943007, 5183.792648294101, 5184.818531351385, 5209.739513024334, 5699.658020518782, 5714.314282286069, 5736.77052917887, 5786.739596718157, 5813.215215945793, 6655.667216639149, 6732.798016059526, 6800.727970881109, 6801.0, 6805.843006688776, 1642.3184378482795, 1675.9245833937503, 1697.1514242878561, 1721.3544920654754, 1766.9663247000392, 1211.3882489342825, 1220.6216073017215, 1228.1341654133994, 1241.472374240944, 1254.2474515290753, 5871.823845284648, 5905.704714764244, 5941.70291485424, 5983.311919129269, 5993.70031498432, 6720.563163394364, 6783.8806596911945, 6800.625965571941, 6823.454123670094, 6823.624700641512, 5765.279340082489, 5924.377940316291, 5947.970260148758, 5948.196993405835, 5951.7619295227705, 3632.727545434103, 3640.7087433005827, 3689.409694448929, 3706.2772759312215, 3707.2770809692393, 6655.0, 6677.565958212701, 6696.354939798753, 6711.362420570135, 6814.591124532544, 7013.4038606719005, 7076.575405475688, 7090.680919358641, 7113.292809725629, 7154.964225178841, 6671.532262902154, 6680.565763225375, 6725.007371719636, 6798.76204332846, 6869.931300687129, 6922.238553758982, 6941.167059952838, 6991.335823096798, 7022.6488675565, 7035.472339574655, 6753.3246675332075, 6810.421114205348, 6822.556533825285, 6889.242183359726, 6975.96512017457, 6663.7672030674485, 6669.699863506057, 6694.359881828983, 6734.764283250062, 6744.022116793136, 4992.176290912104, 5059.974700126623, 5079.720615366043, 5086.3896332440345, 5099.6175286852895, 6970.860582788418, 7024.26245244242, 7046.929530704832, 7053.365197132282, 7067.858642827217, 6520.706568204537, 6605.966970165311, 6664.50016248793, 6737.305077968852, 6774.3225677431865, 6614.66926653675, 6617.338266173344, 6651.368120028589, 6681.732730690718, 6694.230163531256, 6404.711787969646, 6527.314631963575, 6642.368974947372, 6644.302348253364, 6720.563163394364, 6282.8429289266305, 6638.668066596748, 6642.568233064835, 6674.16572928384, 6702.36327548882, 6387.796210307437, 6423.710933008119, 6452.967735161482, 6509.544331896722, 6574.178227721535], "value": 5152.451688214983, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [878.0560897035684, 892.2415946445518, 902.963881444742, 907.2651152566422, 924.6948506992691, 17744.91127544362, 18575.907120464395, 18659.3469228577, 18922.810771892277, 18925.337613183543, 13375.933120334408, 13381.26403047832, 16228.509491389275, 20029.298974535894, 20211.494712632193, 981.1267971505366, 999.8650182225416, 1000.324780772979, 1002.2533212756507, 1002.8044531316397, 950.9762255943588, 987.2052997337141, 992.9900700993006, 993.3642468819961, 995.7162208770502, 1034.151995363802, 1034.462079718546, 1040.953157107928, 1045.7333379988092, 1048.0567489259688, 1036.8392899100652, 1039.6621098143096, 1039.844023396489, 1043.2488608202082, 1043.9634612788554, 754.3550264523808, 763.7594157840263, 766.1495739728897, 768.9500182488121, 770.5453782268488, 19598.0, 19837.80162198374, 19862.90068549648, 19958.401247962585, 20087.598248035105, 20465.795342046687, 20580.691289630668, 21143.5771284575, 21262.149514019577, 21282.787172128392, 16587.66824663512, 17074.658506829917, 17111.31554737809, 17220.74168887455, 17265.482035538953, 3716.739828212026, 3729.7948612826285, 3740.7194460415576, 3743.1390780120723, 3747.025773298935, 131369.0, 131746.0, 132619.0, 133582.33208833894, 134531.32734336265, 15843.52469425896, 15974.121423321829, 15997.68004639924, 16094.597635059217, 16231.756523652155, 28192.577111342936, 28486.715132848407, 28519.714802851708, 29316.0, 29883.0, 7903.209679032051, 8007.83984320322, 8050.993625796776, 8077.111517733045, 8118.35053195743, 3998.7800670962793, 4014.0566966762785, 4114.6091121343425, 4132.772697501667, 4233.830646774156, 5027.0, 5137.0, 5146.588272938156, 5152.046871328816, 5201.661891977085, 1033.7777377863645, 1037.3931250218557, 1075.306427354352, 1083.090204228452, 1086.315621158665, 670.7317073170673, 690.1132045321738, 695.5687473766196, 703.0684343245221, 703.4407645921505, 10845.078168355778, 11208.66374008781, 11313.717157070974, 11461.312321260752, 11676.124290678063, 678.7760039187057, 691.4295706042434, 695.4888157204551, 708.3164746020116, 709.0498731699547, 5331.173668081454, 5453.563714902798, 5465.07093794056, 5468.398476167618, 5506.284183056177, 113709.43145284221, 115442.0, 117815.0, 119844.0, 120900.39549802194, 95229.0, 95940.52029739806, 100958.49520752496, 103494.0, 106511.0, 85902.78228645925, 87339.0, 89262.0, 89707.10292897117, 91440.54279728558, 10683.673112389495, 10935.726606834893, 10999.670009899715, 11647.184697071123, 11987.041036716868, 40084.59915400925, 40698.38952415657, 41222.38166427446, 41881.37177942273, 42070.0, 55043.44956550383, 56990.14514782199, 57031.42968570427, 57275.42724572868, 58006.12990805057, 17246.482605521855, 17341.82658173402, 17467.737983929997, 17586.296548137932, 17711.734323984892, 19149.90425047866, 19216.519587009876, 19406.611867762847, 19558.804411955698, 19783.109760060543, 15505.22473876279, 15618.375264989274, 15689.215539223222, 15863.127527986071, 15897.920510397375, 14898.180600066667, 14962.177080260255, 15130.319135638703, 15281.235938203268, 15558.222088895736, 16145.354185832435, 16464.341426342813, 16502.0, 16503.66992660164, 16623.418180363627, 11779.823302650295, 11850.466728997388, 12057.397130143283, 12139.453724582238, 12367.196132251374, 12395.0, 12644.0, 12762.553310634083, 12965.416556254802, 13054.934725326693], "value": 8826.71395707498, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1529.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 109.0, 101.0, 109.0, 108.0, 109.0, 108.0, 101.0, 117.0, 116.0], "value": 107.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 117.0, 146.0, 186.0, 128.0, 163.0, 147.0, 120.0, 178.0, 118.0], "value": 145.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 170.0, 164.0, 161.0, 163.0, 166.0, 167.0, 176.0, 167.0, 164.0], "value": 166.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 81.0, 191.0, 75.0, 73.0, 76.0, 74.0, 171.0, 87.0], "value": 97.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 130.0, 83.0, 80.0, 80.0, 126.0, 78.0, 80.0, 127.0], "value": 94.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 125.0, 114.0, 114.0, 115.0, 122.0, 116.0, 115.0, 118.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 158.0, 158.0, 156.0, 149.0, 150.0, 152.0, 150.0, 148.0, 150.0], "value": 152.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 98.0, 94.0, 95.0, 102.0, 95.0, 98.0, 95.0, 94.0, 95.0], "value": 96.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 58.0, 54.0, 54.0, 65.0, 61.0, 55.0, 54.0, 58.0, 54.0], "value": 57.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 40.0, 38.0, 48.0, 46.0, 42.0, 42.0, 45.0, 38.0, 52.0], "value": 43.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 75.0, 84.0, 82.0, 82.0, 78.0, 85.0, 78.0, 81.0, 79.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 123.0, 123.0, 120.0, 121.0, 121.0, 126.0, 127.0, 118.0, 164.0], "value": 126.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 178.0, 174.0, 180.0, 184.0, 180.0, 173.0, 173.0, 176.0, 177.0], "value": 176.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 65.0, 68.0, 71.0, 69.0, 64.0, 67.0, 64.0, 65.0], "value": 66.6, "unit": "score"}]}]} 09:36:34 INFO - Return code: 0 09:36:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:36:34 INFO - # TBPL SUCCESS # 09:36:34 INFO - Running post-action listener: _package_coverage_data 09:36:34 INFO - Running post-action listener: _resource_record_post_action 09:36:34 INFO - [mozharness: 2017-09-01 16:36:34.899842Z] Finished run-tests step (success) 09:36:34 INFO - Running post-run listener: _resource_record_post_run 09:36:34 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 319488; Write bytes: 388931584; Read time: 308; Write time: 203832 09:36:34 INFO - TinderboxPrint: CPU usage
13.3% 09:36:34 INFO - TinderboxPrint: I/O read bytes / time
319,488 / 308 09:36:34 INFO - TinderboxPrint: I/O write bytes / time
388,931,584 / 203,832 09:36:34 INFO - TinderboxPrint: CPU idle
8,253.1 (86.7%) 09:36:34 INFO - TinderboxPrint: CPU user
1,202.9 (12.6%) 09:36:34 INFO - TinderboxPrint: Swap in / out
0 / 0 09:36:34 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 107184128; Read time: 32; Write time: 74084 09:36:34 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:36:34 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 315392; Write bytes: 281747456; Read time: 276; Write time: 129748 09:36:35 INFO - Running post-run listener: _upload_blobber_files 09:36:35 INFO - Blob upload gear active. 09:36:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:36:35 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:36:35 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:36:35 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:36:35 INFO - (blobuploader) - INFO - Open directory for files ... 09:36:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:36:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:36:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:37 INFO - (blobuploader) - INFO - Done attempting. 09:36:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:36:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:36:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:40 INFO - (blobuploader) - INFO - Done attempting. 09:36:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:36:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:36:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:41 INFO - (blobuploader) - INFO - Done attempting. 09:36:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:36:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:36:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:42 INFO - (blobuploader) - INFO - Done attempting. 09:36:42 INFO - (blobuploader) - INFO - Iteration through files over. 09:36:42 INFO - Return code: 0 09:36:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:36:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:36:42 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6476caf7e60bc4a2bbe47a8b3d5ab95b2aebda1aa8e2a18cef1f50fbe68ede416250057ea8db93d3972848f64d28c443fe75ee89d2e6c9cdcf087cca140ef354", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83d3d1491003fe0f2e54143732cb3b589c056af128d7b5beb286afb65b9a3eab839fb542d289399cf64f5af3bbcde4bff822a75d3d7d7ab20fbc9ee9c4de921f", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfa60640e424ae9dea510d70f046091e8ca9ea5296f0620cf9c79ec87dde4943b90b90f8c03dd0613bad5eda43cec80867103eb00d028ab63ff7526d9e77187a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a4c0ba3243ba8d932cd17bd502e5cef6eb6e126bbc576a4ae7ea70bb030a7e8bc2070820830bc13d89107027d30f4b2c2a0779d94d8c3c0dfbf6f244d6c39d7"} 09:36:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:36:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:36:42 INFO - Contents: 09:36:42 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6476caf7e60bc4a2bbe47a8b3d5ab95b2aebda1aa8e2a18cef1f50fbe68ede416250057ea8db93d3972848f64d28c443fe75ee89d2e6c9cdcf087cca140ef354", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83d3d1491003fe0f2e54143732cb3b589c056af128d7b5beb286afb65b9a3eab839fb542d289399cf64f5af3bbcde4bff822a75d3d7d7ab20fbc9ee9c4de921f", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfa60640e424ae9dea510d70f046091e8ca9ea5296f0620cf9c79ec87dde4943b90b90f8c03dd0613bad5eda43cec80867103eb00d028ab63ff7526d9e77187a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a4c0ba3243ba8d932cd17bd502e5cef6eb6e126bbc576a4ae7ea70bb030a7e8bc2070820830bc13d89107027d30f4b2c2a0779d94d8c3c0dfbf6f244d6c39d7"} 09:36:42 INFO - Running post-run listener: copy_logs_to_upload_dir 09:36:42 INFO - Copying logs to upload dir... 09:36:42 INFO - mkdir: /builds/slave/test/build/upload/logs 09:36:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.536461 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-09-01 09:36:42.445973) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 09:36:42.449821) ========= 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=0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6476caf7e60bc4a2bbe47a8b3d5ab95b2aebda1aa8e2a18cef1f50fbe68ede416250057ea8db93d3972848f64d28c443fe75ee89d2e6c9cdcf087cca140ef354", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83d3d1491003fe0f2e54143732cb3b589c056af128d7b5beb286afb65b9a3eab839fb542d289399cf64f5af3bbcde4bff822a75d3d7d7ab20fbc9ee9c4de921f", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfa60640e424ae9dea510d70f046091e8ca9ea5296f0620cf9c79ec87dde4943b90b90f8c03dd0613bad5eda43cec80867103eb00d028ab63ff7526d9e77187a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a4c0ba3243ba8d932cd17bd502e5cef6eb6e126bbc576a4ae7ea70bb030a7e8bc2070820830bc13d89107027d30f4b2c2a0779d94d8c3c0dfbf6f244d6c39d7"} build_url:https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011185 build_url: 'https://queue.taskcluster.net/v1/task/HW9QGOoZRpaQ0PfZ0HF4ew/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6476caf7e60bc4a2bbe47a8b3d5ab95b2aebda1aa8e2a18cef1f50fbe68ede416250057ea8db93d3972848f64d28c443fe75ee89d2e6c9cdcf087cca140ef354", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83d3d1491003fe0f2e54143732cb3b589c056af128d7b5beb286afb65b9a3eab839fb542d289399cf64f5af3bbcde4bff822a75d3d7d7ab20fbc9ee9c4de921f", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfa60640e424ae9dea510d70f046091e8ca9ea5296f0620cf9c79ec87dde4943b90b90f8c03dd0613bad5eda43cec80867103eb00d028ab63ff7526d9e77187a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a4c0ba3243ba8d932cd17bd502e5cef6eb6e126bbc576a4ae7ea70bb030a7e8bc2070820830bc13d89107027d30f4b2c2a0779d94d8c3c0dfbf6f244d6c39d7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 09:36:42.476629) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:36:42.476953) ========= 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=0e2a256762fed810e2f0f810000001b3-1504282549.222174-806106027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004551 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:36:42.560577) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 09:36:42.560894) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 09:36:42.561187) ========= ========= Total master_lag: 0.18 =========