builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-073 starttime: 1505686767.66 results: success (0) revision: dd87b8587fc1d63e57ebdadcbabba89c6d172969 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-17 15:19:27.663261) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-17 15:19:27.663898) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-17 15:19:27.664180) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-17 15:19:28.002658) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 15:19:28.003103) ========= 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=9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-17 15:19:28-- 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% 25.3M=0s 2017-09-17 15:19:28 (25.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137619 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 15:19:28.204456) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 15:19:28.204751) ========= 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=9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019949 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 15:19:28.254479) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-09-17 15:19:28.254811) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev dd87b8587fc1d63e57ebdadcbabba89c6d172969 --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 dd87b8587fc1d63e57ebdadcbabba89c6d172969 --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=9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-17 15:19:28,456 truncating revision to first 12 chars 2017-09-17 15:19:28,457 Setting DEBUG logging. 2017-09-17 15:19:28,457 attempt 1/10 2017-09-17 15:19:28,457 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/dd87b8587fc1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-17 15:19:28,926 attempt 1/10 2017-09-17 15:19:29,623 current task status: no status available at this point. state: STARTED 2017-09-17 15:19:29,624 sleeping for 9.00s (attempt 1/10) 2017-09-17 15:19:38,633 attempt 2/10 2017-09-17 15:19:38,971 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-17 15:19:39,167 unpacking tar archive at: mozilla-central-dd87b8587fc1/testing/mozharness/ program finished with exit code 0 elapsedTime=10.987812 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-09-17 15:19:39.260281) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-17 15:19:39.260607) ========= script_repo_revision: dd87b8587fc1d63e57ebdadcbabba89c6d172969 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-17 15:19:39.260960) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-17 15:19:39.261239) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-17 15:19:39.283398) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-09-17 15:19:39.283719) ========= /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=9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:19:39 INFO - MultiFileLogger online at 20170917 15:19:39 in /builds/slave/test 15:19:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:19:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:19:39 INFO - {'append_to_log': False, 15:19:39 INFO - 'base_work_dir': '/builds/slave/test', 15:19:39 INFO - 'blob_upload_branch': 'Firefox', 15:19:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:19:39 INFO - 'branch': 'Firefox', 15:19:39 INFO - 'buildbot_json_path': 'buildprops.json', 15:19:39 INFO - 'code_coverage': False, 15:19:39 INFO - 'config_files': ('talos/linux_config.py',), 15:19:39 INFO - 'default_actions': ('clobber', 15:19:39 INFO - 'read-buildbot-config', 15:19:39 INFO - 'download-and-extract', 15:19:39 INFO - 'populate-webroot', 15:19:39 INFO - 'create-virtualenv', 15:19:39 INFO - 'install', 15:19:39 INFO - 'setup-mitmproxy', 15:19:39 INFO - 'run-tests'), 15:19:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:19:39 INFO - 'disable_ccov_upload': False, 15:19:39 INFO - 'disable_stylo': False, 15:19:39 INFO - 'download_minidump_stackwalk': True, 15:19:39 INFO - 'download_symbols': 'ondemand', 15:19:39 INFO - 'enable_stylo': False, 15:19:39 INFO - 'enable_webrender': False, 15:19:39 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:19:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:19:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:19:39 INFO - 'gecko_profile': False, 15:19:39 INFO - 'gecko_profile_interval': 0, 15:19:39 INFO - 'installer_path': 'installer.exe', 15:19:39 INFO - 'log_level': 'info', 15:19:39 INFO - 'log_name': 'talos', 15:19:39 INFO - 'log_to_console': True, 15:19:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:19:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:19:39 INFO - 'opt_config_files': (), 15:19:39 INFO - 'pip_index': False, 15:19:39 INFO - 'suite': 'dromaeojs-e10s', 15:19:39 INFO - 'system_bits': '32', 15:19:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:19:39 INFO - 'title': 'talos-linux64-ix-073', 15:19:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:19:39 INFO - 'use_talos_json': True, 15:19:39 INFO - 'verify': 'False', 15:19:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:19:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:19:39 INFO - 'work_dir': 'build'} 15:19:39 INFO - [mozharness: 2017-09-17 22:19:39.705886Z] Running clobber step. 15:19:39 INFO - Running pre-action listener: _resource_record_pre_action 15:19:39 INFO - Running main action method: clobber 15:19:39 INFO - rmtree: /builds/slave/test/build 15:19:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:19:40 INFO - Running post-action listener: _resource_record_post_action 15:19:40 INFO - [mozharness: 2017-09-17 22:19:40.647206Z] Finished clobber step (success) 15:19:40 INFO - [mozharness: 2017-09-17 22:19:40.647302Z] Running read-buildbot-config step. 15:19:40 INFO - Running pre-action listener: _resource_record_pre_action 15:19:40 INFO - Running main action method: read_buildbot_config 15:19:40 INFO - Using buildbot properties: 15:19:40 INFO - { 15:19:40 INFO - "project": "", 15:19:40 INFO - "product": "firefox", 15:19:40 INFO - "who": "archaeopteryx@coole-files.de", 15:19:40 INFO - "installer_path": "public/build/target.tar.bz2", 15:19:40 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 15:19:40 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:19:40 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 15:19:40 INFO - "stage_platform": "linux64-qr", 15:19:40 INFO - "basedir": "/builds/slave/test", 15:19:40 INFO - "buildnumber": 71, 15:19:40 INFO - "platform": "ubuntu64_hw_qr", 15:19:40 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:19:40 INFO - "slavebuilddir": "test", 15:19:40 INFO - "taskId": "fRvzGVOyTVCwlpislSpv9Q", 15:19:40 INFO - "branch": "mozilla-central", 15:19:40 INFO - "script_repo_revision": "production", 15:19:40 INFO - "revision": "dd87b8587fc1d63e57ebdadcbabba89c6d172969", 15:19:40 INFO - "slavename": "talos-linux64-ix-073", 15:19:40 INFO - "repo_path": "mozilla-central" 15:19:40 INFO - } 15:19:40 INFO - Finding installer, test and symbols from parent task. 15:19:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fRvzGVOyTVCwlpislSpv9Q'}, attempt #1 15:19:41 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fRvzGVOyTVCwlpislSpv9Q'}, attempt #1 15:19:41 INFO - Task dependencies: Mt_FquQNTb2zZxQoyb7pNg 15:19:41 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mt_FquQNTb2zZxQoyb7pNg'}, attempt #1 15:19:41 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Mt_FquQNTb2zZxQoyb7pNg/artifacts/public/build/target.tar.bz2 15:19:41 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Mt_FquQNTb2zZxQoyb7pNg/artifacts/public/build/target.test_packages.json 15:19:41 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Mt_FquQNTb2zZxQoyb7pNg/artifacts/public/build/target.crashreporter-symbols.zip 15:19:41 INFO - Running post-action listener: _resource_record_post_action 15:19:41 INFO - [mozharness: 2017-09-17 22:19:41.958047Z] Finished read-buildbot-config step (success) 15:19:41 INFO - [mozharness: 2017-09-17 22:19:41.958265Z] Running download-and-extract step. 15:19:41 INFO - Running pre-action listener: _resource_record_pre_action 15:19:41 INFO - Running main action method: download_and_extract 15:19:41 INFO - mkdir: /builds/slave/test/build/tests 15:19:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:41 INFO - trying https://queue.taskcluster.net/v1/task/Mt_FquQNTb2zZxQoyb7pNg/artifacts/public/build/target.test_packages.json 15:19:41 INFO - Downloading https://queue.taskcluster.net/v1/task/Mt_FquQNTb2zZxQoyb7pNg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:19:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mt_FquQNTb2zZxQoyb7pNg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:19:42 INFO - Downloaded 989 bytes. 15:19:42 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:19:42 INFO - Using the following test package requirements: 15:19:42 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:19:42 INFO - u'common': [u'target.common.tests.zip'], 15:19:42 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:19:42 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:19:42 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:19:42 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:19:42 INFO - u'mozbase': [u'target.common.tests.zip'], 15:19:42 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:19:42 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:19:42 INFO - u'web-platform': [u'target.common.tests.zip', 15:19:42 INFO - u'target.web-platform.tests.tar.gz'], 15:19:42 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:19:42 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 15:19:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mt_FquQNTb2zZxQoyb7pNg/artifacts/public/build/target.common.tests.zip 15:19:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mt_FquQNTb2zZxQoyb7pNg/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:19:42 INFO - Fetch https://queue.taskcluster.net/v1/task/Mt_FquQNTb2zZxQoyb7pNg/artifacts/public/build/target.common.tests.zip into memory 15:19:43 INFO - Content-Length response header: 54731175 15:19:43 INFO - Bytes received: 54731175 15:19:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mt_FquQNTb2zZxQoyb7pNg/artifacts/public/build/target.talos.tests.zip 15:19:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mt_FquQNTb2zZxQoyb7pNg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:19:54 INFO - Fetch https://queue.taskcluster.net/v1/task/Mt_FquQNTb2zZxQoyb7pNg/artifacts/public/build/target.talos.tests.zip into memory 15:19:55 INFO - Content-Length response header: 14508748 15:19:55 INFO - Bytes received: 14508748 15:19:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:55 INFO - trying https://queue.taskcluster.net/v1/task/Mt_FquQNTb2zZxQoyb7pNg/artifacts/public/build/target.tar.bz2 15:19:55 INFO - Downloading https://queue.taskcluster.net/v1/task/Mt_FquQNTb2zZxQoyb7pNg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:19:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mt_FquQNTb2zZxQoyb7pNg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:19:56 INFO - Downloaded 61556082 bytes. 15:19:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Mt_FquQNTb2zZxQoyb7pNg/artifacts/public/build/target.tar.bz2 15:19:56 INFO - mkdir: /builds/slave/test/properties 15:19:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:19:56 INFO - Writing to file /builds/slave/test/properties/build_url 15:19:56 INFO - Contents: 15:19:56 INFO - build_url:https://queue.taskcluster.net/v1/task/Mt_FquQNTb2zZxQoyb7pNg/artifacts/public/build/target.tar.bz2 15:19:56 INFO - Running post-action listener: _resource_record_post_action 15:19:56 INFO - Running post-action listener: find_tests_for_verification 15:19:56 INFO - Running post-action listener: set_extra_try_arguments 15:19:56 INFO - [mozharness: 2017-09-17 22:19:56.896538Z] Finished download-and-extract step (success) 15:19:56 INFO - [mozharness: 2017-09-17 22:19:56.896668Z] Running populate-webroot step. 15:19:56 INFO - Running pre-action listener: _resource_record_pre_action 15:19:56 INFO - Running main action method: populate_webroot 15:19:56 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:19:56 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:19:56 INFO - 'tests': ['tresize', 15:19:56 INFO - 'tcanvasmark']}, 15:19:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:19:56 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:19:56 INFO - 'tests': ['dromaeo_css', 15:19:56 INFO - 'kraken']}, 15:19:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:19:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:19:56 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:19:56 INFO - 'talos_options': ['--disable-stylo'], 15:19:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:19:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:19:56 INFO - 'tests': ['damp', 'tps']}, 15:19:56 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:19:56 INFO - 'talos_options': ['--disable-stylo'], 15:19:56 INFO - 'tests': ['damp', 'tps']}, 15:19:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:19:56 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:19:56 INFO - 'tests': ['dromaeo_dom']}, 15:19:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:19:56 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:19:56 INFO - 'tests': ['basic_compositor_video', 15:19:56 INFO - 'glvideo']}, 15:19:56 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:19:56 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:19:56 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:19:56 INFO - 'talos_options': ['--disable-stylo'], 15:19:56 INFO - 'tests': ['ts_paint_webext', 15:19:56 INFO - 'tp5o_webext']}, 15:19:56 INFO - 'other-e10s': {'tests': ['a11yr', 15:19:56 INFO - 'ts_paint', 15:19:56 INFO - 'tpaint', 15:19:56 INFO - 'sessionrestore', 15:19:56 INFO - 'sessionrestore_many_windows', 15:19:56 INFO - 'sessionrestore_no_auto_restore', 15:19:56 INFO - 'tabpaint']}, 15:19:56 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:19:56 INFO - 'tests': ['a11yr', 15:19:56 INFO - 'ts_paint', 15:19:56 INFO - 'tpaint', 15:19:56 INFO - 'sessionrestore', 15:19:56 INFO - 'sessionrestore_many_windows', 15:19:56 INFO - 'sessionrestore_no_auto_restore', 15:19:56 INFO - 'tabpaint']}, 15:19:56 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:19:56 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 15:19:56 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:19:56 INFO - 'tests': ['perf_reftest_singletons']}, 15:19:56 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:19:56 INFO - 'tests': ['bloom_basic']}, 15:19:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:19:56 INFO - 'tsvgr_opacity', 15:19:56 INFO - 'tart', 15:19:56 INFO - 'tscrollx', 15:19:56 INFO - 'tsvg_static']}, 15:19:56 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:19:56 INFO - 'tests': ['tsvgx', 15:19:56 INFO - 'tsvgr_opacity', 15:19:56 INFO - 'tart', 15:19:56 INFO - 'tscrollx', 15:19:56 INFO - 'tsvg_static']}, 15:19:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:19:56 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:19:56 INFO - 'talos_options': ['--disable-stylo'], 15:19:56 INFO - 'tests': ['tp5o']}, 15:19:56 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:19:56 INFO - 'talos_options': ['--mitmproxy', 15:19:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:19:56 INFO - '--firstNonBlankPaint'], 15:19:56 INFO - 'tests': ['tp6_google', 15:19:56 INFO - 'tp6_youtube', 15:19:56 INFO - 'tp6_amazon', 15:19:56 INFO - 'tp6_facebook']}, 15:19:56 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:19:56 INFO - 'talos_options': ['--disable-stylo', 15:19:56 INFO - '--mitmproxy', 15:19:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:19:56 INFO - '--firstNonBlankPaint'], 15:19:56 INFO - 'tests': ['tp6_google', 15:19:56 INFO - 'tp6_youtube', 15:19:56 INFO - 'tp6_amazon', 15:19:56 INFO - 'tp6_facebook']}, 15:19:56 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:19:56 INFO - 'talos_options': ['--stylo-threads=1', 15:19:56 INFO - '--mitmproxy', 15:19:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:19:56 INFO - '--firstNonBlankPaint'], 15:19:56 INFO - 'tests': ['tp6_google', 15:19:56 INFO - 'tp6_youtube', 15:19:56 INFO - 'tp6_amazon', 15:19:56 INFO - 'tp6_facebook']}, 15:19:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:19:56 INFO - 'talos_options': ['--xperf_path', 15:19:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:19:56 INFO - 'tests': ['tp5n']}, 15:19:56 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:19:56 INFO - 'talos_options': ['--disable-stylo', 15:19:56 INFO - '--xperf_path', 15:19:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:19:56 INFO - 'tests': ['tp5n']}}} 15:19:56 INFO - Running post-action listener: _resource_record_post_action 15:19:56 INFO - [mozharness: 2017-09-17 22:19:56.917431Z] Finished populate-webroot step (success) 15:19:56 INFO - [mozharness: 2017-09-17 22:19:56.917520Z] Running create-virtualenv step. 15:19:56 INFO - Running pre-action listener: _resource_record_pre_action 15:19:56 INFO - Running main action method: create_virtualenv 15:19:56 INFO - Creating virtualenv /builds/slave/test/build/venv 15:19:56 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:19:56 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:19:56 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:19:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:19:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:19:57 INFO - Installing distribute.............................................................................................................................................................................................done. 15:19:58 INFO - Installing pip...............done. 15:19:58 INFO - Return code: 0 15:19:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:19:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:19:58 INFO - Reading from file tmpfile_stdout 15:19:58 INFO - Output received: 15:19:58 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:19:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:19:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:58 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 0x1fc21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2332090>, '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 0x2332d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232cf90>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:19:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:19:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:19:58 INFO - Using env: {'DISPLAY': ':0', 15:19:58 INFO - 'HOME': '/home/cltbld', 15:19:58 INFO - 'LANG': 'en_US.UTF-8', 15:19:58 INFO - 'LANGUAGE': 'en_US:en', 15:19:58 INFO - 'LOGNAME': 'cltbld', 15:19:58 INFO - 'MAIL': '/var/mail/cltbld', 15:19:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:58 INFO - 'MOZ_NO_REMOTE': '1', 15:19:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:58 INFO - 'NO_EM_RESTART': '1', 15:19:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:58 INFO - 'PWD': '/builds/slave/test', 15:19:58 INFO - 'SHELL': '/bin/bash', 15:19:58 INFO - 'SHLVL': '1', 15:19:58 INFO - 'TERM': 'linux', 15:19:58 INFO - 'TMOUT': '86400', 15:19:58 INFO - 'USER': 'cltbld', 15:19:58 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 15:19:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:58 INFO - '_': '/tools/buildbot/bin/python'} 15:19:59 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:19:59 INFO - Downloading/unpacking pip>=1.5 15:19:59 INFO - Running setup.py egg_info for package pip 15:19:59 INFO - warning: no files found matching 'pip/cacert.pem' 15:19:59 INFO - warning: no files found matching '*.html' under directory 'docs' 15:19:59 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:19:59 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:19:59 INFO - Installing collected packages: pip 15:19:59 INFO - Found existing installation: pip 1.1 15:19:59 INFO - Uninstalling pip: 15:19:59 INFO - Successfully uninstalled pip 15:19:59 INFO - Running setup.py install for pip 15:19:59 INFO - warning: no files found matching 'pip/cacert.pem' 15:19:59 INFO - warning: no files found matching '*.html' under directory 'docs' 15:19:59 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:19:59 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:19:59 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:19:59 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:19:59 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:19:59 INFO - Successfully installed pip 15:19:59 INFO - Cleaning up... 15:19:59 INFO - Return code: 0 15:19:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:59 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 0x1fc21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2332090>, '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 0x2332d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232cf90>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:20:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:20:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:20:00 INFO - Using env: {'DISPLAY': ':0', 15:20:00 INFO - 'HOME': '/home/cltbld', 15:20:00 INFO - 'LANG': 'en_US.UTF-8', 15:20:00 INFO - 'LANGUAGE': 'en_US:en', 15:20:00 INFO - 'LOGNAME': 'cltbld', 15:20:00 INFO - 'MAIL': '/var/mail/cltbld', 15:20:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:00 INFO - 'MOZ_NO_REMOTE': '1', 15:20:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:00 INFO - 'NO_EM_RESTART': '1', 15:20:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:00 INFO - 'PWD': '/builds/slave/test', 15:20:00 INFO - 'SHELL': '/bin/bash', 15:20:00 INFO - 'SHLVL': '1', 15:20:00 INFO - 'TERM': 'linux', 15:20:00 INFO - 'TMOUT': '86400', 15:20:00 INFO - 'USER': 'cltbld', 15:20:00 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 15:20:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:00 INFO - '_': '/tools/buildbot/bin/python'} 15:20:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:00 INFO - Downloading/unpacking psutil>=3.1.1 15:20:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:20:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:20:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:20:01 INFO - Installing collected packages: psutil 15:20:01 INFO - Running setup.py install for psutil 15:20:01 INFO - building 'psutil._psutil_linux' extension 15:20:01 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:20:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:20:02 INFO - building 'psutil._psutil_posix' extension 15:20:02 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:20:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:20:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:20:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:20:02 INFO - Successfully installed psutil 15:20:02 INFO - Cleaning up... 15:20:02 INFO - Return code: 0 15:20:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:20:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:02 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 0x1fc21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2332090>, '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 0x2332d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232cf90>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:20:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:20:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:20:02 INFO - Using env: {'DISPLAY': ':0', 15:20:02 INFO - 'HOME': '/home/cltbld', 15:20:02 INFO - 'LANG': 'en_US.UTF-8', 15:20:02 INFO - 'LANGUAGE': 'en_US:en', 15:20:02 INFO - 'LOGNAME': 'cltbld', 15:20:02 INFO - 'MAIL': '/var/mail/cltbld', 15:20:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:02 INFO - 'MOZ_NO_REMOTE': '1', 15:20:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:02 INFO - 'NO_EM_RESTART': '1', 15:20:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:02 INFO - 'PWD': '/builds/slave/test', 15:20:02 INFO - 'SHELL': '/bin/bash', 15:20:02 INFO - 'SHLVL': '1', 15:20:02 INFO - 'TERM': 'linux', 15:20:02 INFO - 'TMOUT': '86400', 15:20:02 INFO - 'USER': 'cltbld', 15:20:02 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 15:20:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:02 INFO - '_': '/tools/buildbot/bin/python'} 15:20:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:20:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:20:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:20:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:20:04 INFO - Installing collected packages: mozsystemmonitor 15:20:04 INFO - Running setup.py install for mozsystemmonitor 15:20:04 INFO - Successfully installed mozsystemmonitor 15:20:04 INFO - Cleaning up... 15:20:04 INFO - Return code: 0 15:20:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:20:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:04 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 0x1fc21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2332090>, '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 0x2332d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232cf90>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:20:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:20:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:20:04 INFO - Using env: {'DISPLAY': ':0', 15:20:04 INFO - 'HOME': '/home/cltbld', 15:20:04 INFO - 'LANG': 'en_US.UTF-8', 15:20:04 INFO - 'LANGUAGE': 'en_US:en', 15:20:04 INFO - 'LOGNAME': 'cltbld', 15:20:04 INFO - 'MAIL': '/var/mail/cltbld', 15:20:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:04 INFO - 'MOZ_NO_REMOTE': '1', 15:20:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:04 INFO - 'NO_EM_RESTART': '1', 15:20:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:04 INFO - 'PWD': '/builds/slave/test', 15:20:04 INFO - 'SHELL': '/bin/bash', 15:20:04 INFO - 'SHLVL': '1', 15:20:04 INFO - 'TERM': 'linux', 15:20:04 INFO - 'TMOUT': '86400', 15:20:04 INFO - 'USER': 'cltbld', 15:20:04 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 15:20:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:04 INFO - '_': '/tools/buildbot/bin/python'} 15:20:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:04 INFO - Downloading/unpacking jsonschema==2.5.1 15:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:20:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:20:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:06 INFO - Downloading functools32-3.2.3-2.tar.gz 15:20:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:20:06 INFO - warning: no files found matching '*.txt' 15:20:06 INFO - no previously-included directories found matching 'build' 15:20:06 INFO - no previously-included directories found matching 'dist' 15:20:06 INFO - no previously-included directories found matching '.git*' 15:20:06 INFO - Installing collected packages: jsonschema, functools32 15:20:06 INFO - Running setup.py install for functools32 15:20:06 INFO - warning: no files found matching '*.txt' 15:20:06 INFO - no previously-included directories found matching 'build' 15:20:06 INFO - no previously-included directories found matching 'dist' 15:20:06 INFO - no previously-included directories found matching '.git*' 15:20:06 INFO - Successfully installed jsonschema functools32 15:20:06 INFO - Cleaning up... 15:20:06 INFO - Return code: 0 15:20:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:20:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:06 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 0x1fc21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2332090>, '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 0x2332d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232cf90>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:20:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:20:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:20:06 INFO - Using env: {'DISPLAY': ':0', 15:20:06 INFO - 'HOME': '/home/cltbld', 15:20:06 INFO - 'LANG': 'en_US.UTF-8', 15:20:06 INFO - 'LANGUAGE': 'en_US:en', 15:20:06 INFO - 'LOGNAME': 'cltbld', 15:20:06 INFO - 'MAIL': '/var/mail/cltbld', 15:20:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:06 INFO - 'MOZ_NO_REMOTE': '1', 15:20:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:06 INFO - 'NO_EM_RESTART': '1', 15:20:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:06 INFO - 'PWD': '/builds/slave/test', 15:20:06 INFO - 'SHELL': '/bin/bash', 15:20:06 INFO - 'SHLVL': '1', 15:20:06 INFO - 'TERM': 'linux', 15:20:06 INFO - 'TMOUT': '86400', 15:20:06 INFO - 'USER': 'cltbld', 15:20:06 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 15:20:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:06 INFO - '_': '/tools/buildbot/bin/python'} 15:20:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:20:06 INFO - Cleaning up... 15:20:06 INFO - Return code: 0 15:20:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:20:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:06 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 0x1fc21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2332090>, '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 0x2332d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232cf90>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:20:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:20:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:20:06 INFO - Using env: {'DISPLAY': ':0', 15:20:06 INFO - 'HOME': '/home/cltbld', 15:20:06 INFO - 'LANG': 'en_US.UTF-8', 15:20:06 INFO - 'LANGUAGE': 'en_US:en', 15:20:06 INFO - 'LOGNAME': 'cltbld', 15:20:06 INFO - 'MAIL': '/var/mail/cltbld', 15:20:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:06 INFO - 'MOZ_NO_REMOTE': '1', 15:20:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:06 INFO - 'NO_EM_RESTART': '1', 15:20:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:06 INFO - 'PWD': '/builds/slave/test', 15:20:06 INFO - 'SHELL': '/bin/bash', 15:20:06 INFO - 'SHLVL': '1', 15:20:06 INFO - 'TERM': 'linux', 15:20:06 INFO - 'TMOUT': '86400', 15:20:06 INFO - 'USER': 'cltbld', 15:20:06 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 15:20:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:06 INFO - '_': '/tools/buildbot/bin/python'} 15:20:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:06 INFO - Downloading/unpacking blobuploader==1.2.4 15:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:07 INFO - Downloading blobuploader-1.2.4.tar.gz 15:20:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:20:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:20:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:20:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:08 INFO - Downloading docopt-0.6.1.tar.gz 15:20:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:20:08 INFO - Installing collected packages: blobuploader, requests, docopt 15:20:08 INFO - Running setup.py install for blobuploader 15:20:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:20:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:20:08 INFO - Running setup.py install for requests 15:20:08 INFO - Running setup.py install for docopt 15:20:09 INFO - Successfully installed blobuploader requests docopt 15:20:09 INFO - Cleaning up... 15:20:09 INFO - Return code: 0 15:20:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:20:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:09 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 0x1fc21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2332090>, '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 0x2332d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232cf90>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:20:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:20:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:20:09 INFO - Using env: {'DISPLAY': ':0', 15:20:09 INFO - 'HOME': '/home/cltbld', 15:20:09 INFO - 'LANG': 'en_US.UTF-8', 15:20:09 INFO - 'LANGUAGE': 'en_US:en', 15:20:09 INFO - 'LOGNAME': 'cltbld', 15:20:09 INFO - 'MAIL': '/var/mail/cltbld', 15:20:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:09 INFO - 'MOZ_NO_REMOTE': '1', 15:20:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:09 INFO - 'NO_EM_RESTART': '1', 15:20:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:09 INFO - 'PWD': '/builds/slave/test', 15:20:09 INFO - 'SHELL': '/bin/bash', 15:20:09 INFO - 'SHLVL': '1', 15:20:09 INFO - 'TERM': 'linux', 15:20:09 INFO - 'TMOUT': '86400', 15:20:09 INFO - 'USER': 'cltbld', 15:20:09 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 15:20:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:09 INFO - '_': '/tools/buildbot/bin/python'} 15:20:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:20:09 INFO - Running setup.py (path:/tmp/pip-MvSmgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:20:09 INFO - Running setup.py (path:/tmp/pip-9v_hun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:20:09 INFO - Running setup.py (path:/tmp/pip-RfJSYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:20:09 INFO - Running setup.py (path:/tmp/pip-HfkxiB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:20:09 INFO - Running setup.py (path:/tmp/pip-gi5pPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:20:09 INFO - Running setup.py (path:/tmp/pip-yzrNcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:20:09 INFO - Running setup.py (path:/tmp/pip-mpTs6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:20:09 INFO - Running setup.py (path:/tmp/pip-b6yjPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:20:09 INFO - Running setup.py (path:/tmp/pip-gCnVKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:20:09 INFO - Running setup.py (path:/tmp/pip-O0TBdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:20:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:20:10 INFO - Running setup.py (path:/tmp/pip-ZQTVsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:20:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:20:10 INFO - Running setup.py (path:/tmp/pip-Wq5OP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:20:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:20:10 INFO - Running setup.py (path:/tmp/pip-_oMdKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:20:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:20:10 INFO - Running setup.py (path:/tmp/pip-td_H2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:20:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:10 INFO - Running setup.py (path:/tmp/pip-715i4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:20:10 INFO - Running setup.py (path:/tmp/pip-cwI4wR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:20:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:20:10 INFO - Running setup.py (path:/tmp/pip-6cd85V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:20:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:20:10 INFO - Running setup.py install for manifestparser 15:20:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:20:10 INFO - Running setup.py install for mozcrash 15:20:10 INFO - Running setup.py install for mozdebug 15:20:10 INFO - Running setup.py install for mozdevice 15:20:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:20:10 INFO - Running setup.py install for mozfile 15:20:11 INFO - Running setup.py install for mozhttpd 15:20:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:20:11 INFO - Running setup.py install for mozinfo 15:20:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:20:11 INFO - Running setup.py install for mozInstall 15:20:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:20:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:20:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:20:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:20:11 INFO - Running setup.py install for mozleak 15:20:11 INFO - Running setup.py install for mozlog 15:20:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:20:11 INFO - Running setup.py install for moznetwork 15:20:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:20:11 INFO - Running setup.py install for mozprocess 15:20:11 INFO - Running setup.py install for mozprofile 15:20:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:20:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:20:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:20:11 INFO - Running setup.py install for mozrunner 15:20:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:20:11 INFO - Running setup.py install for mozscreenshot 15:20:12 INFO - Running setup.py install for moztest 15:20:12 INFO - Running setup.py install for mozversion 15:20:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:20:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:20:12 INFO - Cleaning up... 15:20:12 INFO - Return code: 0 15:20:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:20:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:12 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 0x1fc21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2332090>, '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 0x2332d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232cf90>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:20:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:20:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:20:12 INFO - Using env: {'DISPLAY': ':0', 15:20:12 INFO - 'HOME': '/home/cltbld', 15:20:12 INFO - 'LANG': 'en_US.UTF-8', 15:20:12 INFO - 'LANGUAGE': 'en_US:en', 15:20:12 INFO - 'LOGNAME': 'cltbld', 15:20:12 INFO - 'MAIL': '/var/mail/cltbld', 15:20:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:12 INFO - 'MOZ_NO_REMOTE': '1', 15:20:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:12 INFO - 'NO_EM_RESTART': '1', 15:20:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:12 INFO - 'PWD': '/builds/slave/test', 15:20:12 INFO - 'SHELL': '/bin/bash', 15:20:12 INFO - 'SHLVL': '1', 15:20:12 INFO - 'TERM': 'linux', 15:20:12 INFO - 'TMOUT': '86400', 15:20:12 INFO - 'USER': 'cltbld', 15:20:12 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 15:20:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:12 INFO - '_': '/tools/buildbot/bin/python'} 15:20:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:20:12 INFO - Running setup.py (path:/tmp/pip-CkldmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:20:12 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:20:12 INFO - Running setup.py (path:/tmp/pip-wiCx2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:20:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:20:12 INFO - Running setup.py (path:/tmp/pip-M9HgpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:20:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:20:12 INFO - Running setup.py (path:/tmp/pip-0M3l1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:20:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:20:12 INFO - Running setup.py (path:/tmp/pip-ljyzl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:20:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:20:12 INFO - Running setup.py (path:/tmp/pip-FYdpi1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:20:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:20:12 INFO - Running setup.py (path:/tmp/pip-mpO6yz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:20:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:20:12 INFO - Running setup.py (path:/tmp/pip-QrEsMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:20:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:20:13 INFO - Running setup.py (path:/tmp/pip-zzAzRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:20:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:20:13 INFO - Running setup.py (path:/tmp/pip-vsbez5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:20:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:20:13 INFO - Running setup.py (path:/tmp/pip-JLYfEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:20:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:20:13 INFO - Running setup.py (path:/tmp/pip-oUqT12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:20:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:20:13 INFO - Running setup.py (path:/tmp/pip-fc0kQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:20:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:20:13 INFO - Running setup.py (path:/tmp/pip-_g9eqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:20:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:13 INFO - Running setup.py (path:/tmp/pip-Tj6kJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:20:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:20:13 INFO - Running setup.py (path:/tmp/pip-VDJsFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:20:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:20:13 INFO - Running setup.py (path:/tmp/pip-7ZV5o6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:20:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:14 INFO - Downloading blessings-1.6.tar.gz 15:20:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:20:14 INFO - Installing collected packages: blessings 15:20:14 INFO - Running setup.py install for blessings 15:20:15 INFO - Successfully installed blessings 15:20:15 INFO - Cleaning up... 15:20:15 INFO - Return code: 0 15:20:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:20:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:20:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:20:15 INFO - Reading from file tmpfile_stdout 15:20:15 INFO - Current package versions: 15:20:15 INFO - argparse == 1.2.1 15:20:15 INFO - blessings == 1.6 15:20:15 INFO - blobuploader == 1.2.4 15:20:15 INFO - docopt == 0.6.1 15:20:15 INFO - functools32 == 3.2.3-2 15:20:15 INFO - jsonschema == 2.5.1 15:20:15 INFO - manifestparser == 1.1 15:20:15 INFO - mozInstall == 1.13 15:20:15 INFO - mozcrash == 1.0 15:20:15 INFO - mozdebug == 0.1 15:20:15 INFO - mozdevice == 0.51 15:20:15 INFO - mozfile == 1.2 15:20:15 INFO - mozhttpd == 0.7 15:20:15 INFO - mozinfo == 0.10 15:20:15 INFO - mozleak == 0.1 15:20:15 INFO - mozlog == 3.5 15:20:15 INFO - moznetwork == 0.27 15:20:15 INFO - mozprocess == 0.25 15:20:15 INFO - mozprofile == 0.28 15:20:15 INFO - mozrunner == 6.13 15:20:15 INFO - mozscreenshot == 0.1 15:20:15 INFO - mozsystemmonitor == 0.3 15:20:15 INFO - moztest == 0.8 15:20:15 INFO - mozversion == 1.4 15:20:15 INFO - psutil == 3.1.1 15:20:15 INFO - requests == 1.2.3 15:20:15 INFO - wsgiref == 0.1.2 15:20:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:20:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:15 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 0x1fc21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2332090>, '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 0x2332d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232cf90>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:20:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:20:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:20:15 INFO - Using env: {'DISPLAY': ':0', 15:20:15 INFO - 'HOME': '/home/cltbld', 15:20:15 INFO - 'LANG': 'en_US.UTF-8', 15:20:15 INFO - 'LANGUAGE': 'en_US:en', 15:20:15 INFO - 'LOGNAME': 'cltbld', 15:20:15 INFO - 'MAIL': '/var/mail/cltbld', 15:20:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:15 INFO - 'MOZ_NO_REMOTE': '1', 15:20:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:15 INFO - 'NO_EM_RESTART': '1', 15:20:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:15 INFO - 'PWD': '/builds/slave/test', 15:20:15 INFO - 'SHELL': '/bin/bash', 15:20:15 INFO - 'SHLVL': '1', 15:20:15 INFO - 'TERM': 'linux', 15:20:15 INFO - 'TMOUT': '86400', 15:20:15 INFO - 'USER': 'cltbld', 15:20:15 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 15:20:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:15 INFO - '_': '/tools/buildbot/bin/python'} 15:20:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:20:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:20:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:20:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:20:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:20:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:20:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:20:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:20:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:20:15 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:20:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:20:16 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:20:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:20:16 INFO - Installing collected packages: simplejson 15:20:16 INFO - Running setup.py install for simplejson 15:20:16 INFO - building 'simplejson._speedups' extension 15:20:16 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 15:20:17 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 15:20:17 INFO - Successfully installed simplejson 15:20:17 INFO - Cleaning up... 15:20:17 INFO - Return code: 0 15:20:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:20:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:17 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 0x1fc21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2332090>, '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 0x2332d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232cf90>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:20:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:20:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:20:17 INFO - Using env: {'DISPLAY': ':0', 15:20:17 INFO - 'HOME': '/home/cltbld', 15:20:17 INFO - 'LANG': 'en_US.UTF-8', 15:20:17 INFO - 'LANGUAGE': 'en_US:en', 15:20:17 INFO - 'LOGNAME': 'cltbld', 15:20:17 INFO - 'MAIL': '/var/mail/cltbld', 15:20:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:17 INFO - 'MOZ_NO_REMOTE': '1', 15:20:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:17 INFO - 'NO_EM_RESTART': '1', 15:20:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:17 INFO - 'PWD': '/builds/slave/test', 15:20:17 INFO - 'SHELL': '/bin/bash', 15:20:17 INFO - 'SHLVL': '1', 15:20:17 INFO - 'TERM': 'linux', 15:20:17 INFO - 'TMOUT': '86400', 15:20:17 INFO - 'USER': 'cltbld', 15:20:17 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 15:20:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:17 INFO - '_': '/tools/buildbot/bin/python'} 15:20:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:20:17 INFO - Cleaning up... 15:20:17 INFO - Return code: 0 15:20:17 INFO - Running post-action listener: _resource_record_post_action 15:20:17 INFO - Running post-action listener: _start_resource_monitoring 15:20:17 INFO - Starting resource monitoring. 15:20:17 INFO - [mozharness: 2017-09-17 22:20:17.999643Z] Finished create-virtualenv step (success) 15:20:18 INFO - [mozharness: 2017-09-17 22:20:18.000647Z] Running install step. 15:20:18 INFO - Running pre-action listener: _resource_record_pre_action 15:20:18 INFO - Running main action method: install 15:20:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:20:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:20:18 INFO - Reading from file tmpfile_stdout 15:20:18 INFO - Detecting whether we're running mozinstall >=1.0... 15:20:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:20:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:20:18 INFO - Reading from file tmpfile_stdout 15:20:18 INFO - Output received: 15:20:18 INFO - Usage: mozinstall [options] installer 15:20:18 INFO - Options: 15:20:18 INFO - -h, --help show this help message and exit 15:20:18 INFO - -d DEST, --destination=DEST 15:20:18 INFO - Directory to install application into. [default: 15:20:18 INFO - "/builds/slave/test"] 15:20:18 INFO - --app=APP Application being installed. [default: firefox] 15:20:18 INFO - mkdir: /builds/slave/test/build/application 15:20:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:20:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:20:36 INFO - Reading from file tmpfile_stdout 15:20:36 INFO - Output received: 15:20:36 INFO - /builds/slave/test/build/application/firefox/firefox 15:20:36 INFO - Running post-action listener: _resource_record_post_action 15:20:36 INFO - [mozharness: 2017-09-17 22:20:36.088745Z] Finished install step (success) 15:20:36 INFO - [mozharness: 2017-09-17 22:20:36.088892Z] Running setup-mitmproxy step. 15:20:36 INFO - Running pre-action listener: _resource_record_pre_action 15:20:36 INFO - Running main action method: setup_mitmproxy 15:20:36 INFO - Skipping: mitmproxy is not required 15:20:36 INFO - Running post-action listener: _resource_record_post_action 15:20:36 INFO - [mozharness: 2017-09-17 22:20:36.089425Z] Finished setup-mitmproxy step (success) 15:20:36 INFO - [mozharness: 2017-09-17 22:20:36.089655Z] Running run-tests step. 15:20:36 INFO - Running pre-action listener: _resource_record_pre_action 15:20:36 INFO - Running pre-action listener: _set_gcov_prefix 15:20:36 INFO - Running main action method: run_tests 15:20:36 WARNING - Try message not found. 15:20:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:20:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:20:36 INFO - Python 2.7.3 15:20:36 INFO - Return code: 0 15:20:36 INFO - grabbing minidump binary from tooltool 15:20:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:36 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 0x2332090>, '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 0x2332d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232cf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:20:36 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:20:36 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:20:36 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:20:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:20:36 INFO - Return code: 0 15:20:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:20:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:20:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:20:36 INFO - ENV: RUST_BACKTRACE is now full 15:20:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:20:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:20:36 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/Mt_FquQNTb2zZxQoyb7pNg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-073', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:20:36 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/Mt_FquQNTb2zZxQoyb7pNg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-073 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:20:36 INFO - Using env: {'DISPLAY': ':0', 15:20:36 INFO - 'HOME': '/home/cltbld', 15:20:36 INFO - 'LANG': 'en_US.UTF-8', 15:20:36 INFO - 'LANGUAGE': 'en_US:en', 15:20:36 INFO - 'LOGNAME': 'cltbld', 15:20:36 INFO - 'MAIL': '/var/mail/cltbld', 15:20:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:20:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:20:36 INFO - 'MOZ_ACCELERATED': '1', 15:20:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:36 INFO - 'MOZ_NO_REMOTE': '1', 15:20:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:20:36 INFO - 'MOZ_WEBRENDER': '1', 15:20:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:36 INFO - 'NO_EM_RESTART': '1', 15:20:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:36 INFO - 'PWD': '/builds/slave/test', 15:20:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:20:36 INFO - 'RUST_BACKTRACE': 'full', 15:20:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:20:36 INFO - 'SHELL': '/bin/bash', 15:20:36 INFO - 'SHLVL': '1', 15:20:36 INFO - 'TERM': 'linux', 15:20:36 INFO - 'TMOUT': '86400', 15:20:36 INFO - 'USER': 'cltbld', 15:20:36 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 15:20:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:36 INFO - '_': '/tools/buildbot/bin/python'} 15:20:36 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/Mt_FquQNTb2zZxQoyb7pNg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-073', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:20:36 INFO - mozversion application_buildid: 20170917213019 15:20:36 INFO - mozversion application_changeset: dd87b8587fc1d63e57ebdadcbabba89c6d172969 15:20:36 INFO - mozversion application_display_name: Nightly 15:20:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:20:36 INFO - mozversion application_name: Firefox 15:20:36 INFO - mozversion application_remotingname: firefox 15:20:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:20:36 INFO - mozversion application_vendor: Mozilla 15:20:36 INFO - mozversion application_version: 57.0a1 15:20:36 INFO - mozversion platform_buildid: 20170917213019 15:20:36 INFO - mozversion platform_changeset: dd87b8587fc1d63e57ebdadcbabba89c6d172969 15:20:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:20:36 INFO - mozversion platform_version: 57.0a1 15:20:36 INFO - using testdate: 1505686836 15:20:36 INFO - actual date: 1505686836 15:20:36 INFO - starting webserver on 'localhost:57854' 15:20:36 INFO - SUITE-START | Running 2 tests 15:20:36 INFO - TEST-START | dromaeo_css 15:20:36 INFO - Initialising browser for dromaeo_css test... 15:20:36 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57854/getInfo.html -profile /tmp/tmpZYcWVW/profile 15:20:36 INFO - TEST-INFO | started process 3535 (/builds/slave/test/build/application/firefox/firefox http://localhost:57854/getInfo.html) 15:20:44 INFO - TEST-INFO | 3535: exit 0 15:20:44 INFO - Browser initialized. 15:20:44 INFO - Running cycle 1/1 for dromaeo_css test... 15:20:44 INFO - Using env: {'DISPLAY': ':0', 15:20:44 INFO - 'HOME': '/home/cltbld', 15:20:44 INFO - 'JSGC_DISABLE_POISONING': '1', 15:20:44 INFO - 'LANG': 'en_US.UTF-8', 15:20:44 INFO - 'LANGUAGE': 'en_US:en', 15:20:44 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:20:44 INFO - 'LOGNAME': 'cltbld', 15:20:44 INFO - 'MAIL': '/var/mail/cltbld', 15:20:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:20:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:20:44 INFO - 'MOZ_ACCELERATED': '1', 15:20:44 INFO - 'MOZ_CRASHREPORTER': '1', 15:20:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:44 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:20:44 INFO - 'MOZ_NO_REMOTE': '1', 15:20:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:20:44 INFO - 'MOZ_WEBRENDER': '1', 15:20:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:44 INFO - 'NO_EM_RESTART': '1', 15:20:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:44 INFO - 'PWD': '/builds/slave/test', 15:20:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:20:44 INFO - 'RUST_BACKTRACE': 'full', 15:20:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:20:44 INFO - 'SHELL': '/bin/bash', 15:20:44 INFO - 'SHLVL': '1', 15:20:44 INFO - 'TERM': 'linux', 15:20:44 INFO - 'TMOUT': '86400', 15:20:44 INFO - 'USER': 'cltbld', 15:20:44 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 15:20:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:44 INFO - '_': '/tools/buildbot/bin/python'} 15:20:44 INFO - TEST-INFO | started process 3781 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZYcWVW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:20:45 INFO - PID 3781 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:20:45 INFO - PID 3781 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:20:46 INFO - PID 3781 | 15:20:46 INFO - PID 3781 | (/builds/slave/test/build/application/firefox/firefox:3841): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:20:46 INFO - PID 3781 | 15:20:56 INFO - PID 3781 | RSS: Main: 208052224 15:20:56 INFO - PID 3781 | 15:23:54 INFO - PID 3781 | Cycle 1(1): loaded http://localhost:57854/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57854/tests/dromaeo/cssquery-ext.html) 15:23:55 INFO - PID 3781 | RSS: Main: 213204992 15:23:55 INFO - PID 3781 | 15:26:57 INFO - PID 3781 | Cycle 1(1): loaded http://localhost:57854/tests/dromaeo/cssquery-ext.html (next: http://localhost:57854/tests/dromaeo/cssquery-jquery.html) 15:26:58 INFO - PID 3781 | RSS: Main: 214401024 15:26:58 INFO - PID 3781 | 15:30:00 INFO - PID 3781 | Cycle 1(1): loaded http://localhost:57854/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57854/tests/dromaeo/cssquery-mootools.html) 15:30:01 INFO - PID 3781 | RSS: Main: 215519232 15:30:01 INFO - PID 3781 | 15:33:03 INFO - PID 3781 | Cycle 1(1): loaded http://localhost:57854/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57854/tests/dromaeo/cssquery-prototype.html) 15:33:03 INFO - PID 3781 | RSS: Main: 214605824 15:33:03 INFO - PID 3781 | 15:36:07 INFO - PID 3781 | Cycle 1(1): loaded http://localhost:57854/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57854/tests/dromaeo/cssquery-yui.html) 15:36:07 INFO - PID 3781 | RSS: Main: 215363584 15:36:07 INFO - PID 3781 | 15:39:09 INFO - PID 3781 | Cycle 1(1): loaded http://localhost:57854/tests/dromaeo/cssquery-yui.html (next: http://localhost:57854/tests/dromaeo/cssquery-dojo.html) 15:39:10 INFO - PID 3781 | RSS: Main: 217845760 15:39:10 INFO - PID 3781 | 15:39:10 INFO - PID 3781 | __start_tp_report 15:39:10 INFO - PID 3781 | _x_x_mozilla_page_load 15:39:10 INFO - PID 3781 | _x_x_mozilla_page_load_details 15:39:10 INFO - PID 3781 | |i|pagename|runs| 15:39:10 INFO - PID 3781 | |0;dojo.html;3102.0383681058875;3115.205622566246;3139.4819854723974;3152;3158.5104308832124;6045.063015232635;6203.41067598578;6221.7822376216845;6244.531660125492;6338.9999999999945;6137.877242455148;6148.262208534982;6186.072089186613;6189.659568723719;6244.375562443754;5712.542996560275;5716.1711551824965;5731.512821410164;5738.741756620962;5787.855303617423;5821.359650438449;5828.446297601721;5858.472737453629;5859.736311865955;5915.438033386821;5779.393163717812;5780.653160810344;5789.189513468118;5794.710264486759;5883.264591926009;5297.841064768063;5556.472135147154;5621.156826476062;5645.633033852787;5652.604317697763;5691.06097493913;5711.257536520226;5730.713464326767;5736.737917658109;5752.597318187729;6060.33336333003;6216.471599914013;6242.094896240065;6274.749010039593;6296.1500197473215;6547.9345206547805;6553.213614366259;6583.07836902834;6601.438877695402;6602;6611.371919667624;6636.435902948255;6667.899981500282;6671.2995135510555;6681.465482761368;6126.8774624507705;6183.165272688219;6241;6248.687565621746;6306.990881458991;6814.1482314710665;6897.41371983377;6947.79156625302;6956.991236270713;6984.4412447004115;6344.936550634527;6601.240857301472;6602;6663;6697.665116744143;6545.312742162099;6619.933800662028;6687.665616719145;6722.327767223239;6753.527253092335;6628.23775265841;6707.932920670828;6716.194056713128;6735.595864248161;6742.089817874622;6698.5980841149685;6720;6785.389314961677;6794.728210871609;6796.830079248059;5261.263423120766;5270.78916843322;5287.867803304871;5291.444398338196;5299.099153143898;4626.907461850744;4632.656529606444;4638.257878739453;4693.413323334583;4721.527847215251;5696.259486266758;5746.655200687888;5769.653820770767;5782.074868021096;5808.854778630568;6521.250056243498;6622.966885165543;6641.369069938348;6707.3628005339415;6719.496037797185;5542.420410183078;5692.544596432275;5694.430556944272;5709.800156994485;5790.449907258803;5705.857353566194;5790.855228619318;5802.245708057925;5803.274590676166;5803.448672376117;6476.158099447136;6486.091947127406;6554.80335589933;6562.146920900253;6579.078928949951;6486.772962946368;6567.540272180901;6621.238557565749;6629.370209830058;6651.301613330724;6294.464970477561;6366.4588509977175;6368.968155159195;6372.9362706374195;6381.968090159519;6471.320511346241;6482.416582507597;6531;6533.705983230861;6609.8017059486365;6709.429698475684;6717.664116794239;6741.325867413219;6789.287124852017;6805.931940680728;6401.55189136756;6464.450521705519;6486.5459417842185;6505.51208659343;6531.052997315458;6273.059041143682;6420.775272865428;6458.192725909262;6484.610923344614;6528.281888992112;6379.425851673372;6398.360163983564;6438.130852334875;6464;6476.737036278069;6438.227412710505;6501.9674901625185;6566.474682025331;6575.704093315717;6604.14146160996;6268.091126786681;6315.684215789284;6401.583897046677;6404.295527492065;6409 15:39:10 INFO - PID 3781 | |1;ext.html;850.8808766772651;855.7732200966741;857.1257317536114;864.5331520978668;889.9332550058747;37831.62168378315;38937.22125557487;39210.411843822294;39304.213915721666;39383.606163938384;35948.281034379295;36627.63372366276;36678.449823252675;36704;36792.632073679255;6878.82802929927;7001.404880585131;7004.089468369105;7020.157581090276;7021.122359705037;6751.493637977147;6822;6845.315468453165;6855.725770969155;6861.759838405656;6830.521863469559;6844.555103918255;7521.210272921347;7747.2640099190485;7769.533827970312;7650.579218143;7803.258690424401;7808.102068262166;7810.375169986388;7817.609119544;7315.9634201829185;7633.847323053508;7670.4247181461615;7718.382529397691;7730.304272615435;36217.45673814895;36903.26193476142;36918;36921.81539092314;37003.4449483258;3488.4244099723514;3492.1269682579355;3507.719382449398;3508.245727168671;3508.614052454228;30179.849100754574;30343.241418964702;30393.848030759927;30400.695993040008;30615.234619134477;5071.3407257056715;5136.101182293084;5155.71643559604;5175.741212939338;5258.97370513145;176410;180231;189908;197573;197680;45556.54443455589;45928;46038.769806151424;46300.7684961573;46311.073778524915;47081.29378059332;47100.7644961773;47104;47138.76430617825;47461.52538474571;15421.537353879401;15446.691066178613;15537.766933496006;15847.128407937675;15904.284307206204;13133.60599182026;13265.469381224835;13271.66820829487;13275.26986015759;13284.800727989088;13849.653758656113;14020.85979140216;14027.29863506841;14030.298485075706;14160.017597536353;5757.596968212269;5804.709764511757;5870.618409803349;5878.970605146947;5896.469317761336;971.9319647624599;996.6461906023402;1004.487711267259;1008.8940661230612;1017.165923942375;22345;22398.77601223967;22521.549569008526;22801.885990570274;22859;1080.5569716416232;1109.6060898380958;1119.776044791045;1120.176670147441;1128.5090985421368;12007.399630018464;12091.516339346503;12213.314187706777;12214.450349734283;12243.877561224273;223170;223652;224284;224316;224907;49765.25352119721;50005.74997124991;50144.749276254115;50427.24359134616;50437.49562504328;1364.522417154008;1443.1557538839895;1451.0350616839683;1457.5263039512201;1458.2344269258724;21788.89105554462;21809.23667671624;22057.889710551346;22151.889240553694;22295.77704222937;35517.82241088778;35519.289614208086;39899.2020159601;40915;45926;188143;188366;188980;189137;190704;32207.838960805046;32548.83725581357;32707;32826;32892.671073289916;41002.58997409988;41036.79481602573;41164.38253426261;41605.37591936184;41846.58153418427;30444;30663.693363066082;30683.846580766953;30855.69144308528;31260.843695781376;30312.09063728092;30675;30871.69128308778;30942.22644433907;31349.8432507836;33565.66434335626;33735.662643374235;34008.65991340055;34035.319293614484;34143.487847681805;29176;29527.557086643286;29898.103056908327;30210.09369718912;30424.543631845983;22483.21308754186;22538;22700.545989079794;22868.4282892929;23208.651870221624 15:39:10 INFO - PID 3781 | |2;jquery.html;552.3316786688107;552.5331095224535;558.3997203006769;559.9944000559993;569.7493103034665;21521;22443.775562244373;22510.66234006491;22553.661695074567;22628.8868555657;23743.881280593618;24771.75228247717;25061.24816255515;25366.619500707504;25405;6354.173957385545;6526.706298216592;6565.573237739555;6602.306757790435;6606.570572912769;6463.321351258121;6499.577527460724;6515.446187074104;6554.901676474857;6555.311692272314;6410.647414392207;6508.967455162718;6528.249251336086;6529.053287273343;6669.899951500732;6611.471082313404;6644.634545100018;6648.634325112117;6651.93348066518;6653.4344580710695;6087.360827113133;6090;6104.420080092428;6121.479674227651;6149.877002459971;27515.31211719723;28468.572971405447;28611.57082643762;28646.140615781558;28776.568351474743;1946.240966023252;2004.7894971028124;2015.9294424695213;2024.5647185854964;2034.4201902457728;18570.814291857045;19286.517837054045;19531.511712207168;19669.704954425695;19700.70448943267;2415.7221919479134;2475.10896077412;2499.462615537668;2501.862397568133;2506.6490691303234;118566;123994.38002809929;141626;145424.272878636;145977.27011364876;14299.285035748379;14364.640883977983;14615.92692036533;14684.706305873822;14708.55874323772;22177.77822221757;22200.333989980325;22279.220227291968;22322.330330090124;22358;9764.121229089416;9991.100800927952;9991.650292239881;10002.949985250027;10240.078392944672;9203.171714545722;9281.62873485053;9294.442333460014;9323.393979391454;9396.154346108882;8799.076097009849;8850.424722393023;8890;8991.370604057784;9029.503377314311;2580.7290234525103;2581.70310414301;2620.580707086895;2627.710951795301;2689;960.6397600899662;975.7169322341099;978.4911845840123;983.1937810994915;986.0484632329836;18765.7185142223;18822.905885470485;18950.24199032051;19423.223071077282;19544.02279886;1025.5948900184385;1030.407515678473;1034.0848349210853;1038.117600039975;1048.8531605575226;10792.622258220925;10794.514246858753;10921.672349829518;10921.890781092246;10948.233623646269;451682;459468;462297;464640;465430;106797;107184.4640776791;107265.4636726827;107856;108661;1196.4257156564724;1206.849143857018;1220.0666490135054;1223.1071317937956;1223.2660403757852;17495.912520437316;17639.206235719168;17642.20610072524;17755.289788408834;17872;55208;55717.44282557123;55848.44151558432;55884.72057639686;57014.714926425106;65711;66350.66824666038;66726;68411.65794170997;69517.30482695108;13577.660558485723;14090.436382544583;14174;14191.219482928538;14240.287985600471;22178.1128754848;22387.216447424264;22538.887305563367;22619.547609048055;23009.65485517752;17323.48029559065;17330.39343622967;17495.562610934827;17508.912455437643;17545.56136096608;15662.608434789221;16324.918375408048;16443.342266309213;16724.74912876332;16838.747418788484;17542.561435964202;17608.38370657021;17996.64006719832;18094.81905180984;18346.449606511826;13786.517471888837;13793.517226897011;13838;13916.234607096707;14024.08843425174;17724.645507090045;17750.11249437497;17888.373906913195;17970.460886173434;18007.81992180114 15:39:10 INFO - PID 3781 | |3;mootools.html;1307.7057662026045;1362.2575696245544;1369.0827145812298;1375.9656008599786;1377.2081053394302;15100.773488397668;15556.222188890553;15673.921630391833;15674.843251567481;15695.686086278281;15338.846611533882;15468.303926323342;15513.767293490606;15542.378304867792;15601;2494.114589320792;2591.235585502272;2592.053900326386;2595.5976823592277;2596.72734362892;2677.1968409477176;2746.148693904886;2751.5735061065566;2760.861956902157;2761.4339060492653;2648.7616114549687;2663.533881570727;2704.094128466956;2745.052956729935;2749.092799376211;2637.1561100447866;2643.841369517835;2664.2806442260676;2670.8263962842548;2671.479061582992;2547.541442540342;2598.1815728045603;2608.752168543985;2622.6983896851916;2632.3682316244153;14938.62653433662;15246.313915873812;15248.847511524964;15255.847441525553;15291.541253762405;7768.145503994556;8035.718749843727;8039.276465118168;8040.718574849852;8063.435559510836;5094.79620815167;5209.843704688866;5398.4871437213405;5421.159720243369;5431.6740995540395;1939.4084804134734;1951.4535929939639;1970.881747095179;1986.1757370691146;2007.9196832126697;23230;24208.1527146549;25813.3546661335;26223.73776262251;26232.8688356557;6922.788512010378;7036.437085033185;7094.92905070953;7239.167495737952;7245.492815502864;19817.405477835688;19872.60254794896;19913.303034393728;20045.699314510293;20061.197552097754;8012.238837310446;8151.429399942066;8299.83400332002;8304.626291816761;8306.70926517569;7140.285971402818;7382.778516644509;7384.372328352043;7462.738804141829;7506.324430801144;4977.751112444364;5069.746512674352;5106.080905436983;5140.742962851842;5192.4547922468355;1718.3556166437586;1719.1178446821464;1727.464486009341;1729.4119999200332;1735.7830271216098;868.370431437203;870.4472659861034;892.5983307511646;896.2292428511475;897.555709923579;7325.633718314063;7388.926110738824;7403.778376567806;7409.407247420193;7507.21174276704;685.266764561914;686.1560280854477;693.7121094745725;705.5801797930224;711.5872793779596;4251.80866860992;4392.38506609075;4418.889527761831;4437;4440.578145076212;20598.897005514875;21010.159593616085;21832.563348732936;21885.234016809653;21896.562068758536;21406.357809265748;21482.462938426663;21487;21640.134394624354;21719.891400542896;10417.854036056027;11305.095592352756;11336.94331528337;11366.204365694322;11440.713982150346;7812.335951444191;7818.491798033109;7892.0924093729955;7912.208779122042;7962.641681124479;18140.546486337946;18598.721019184453;18651.253949842398;18963.810361896205;19426.514337141685;19507.414777556696;19619.70570441473;19724.30964916221;19800.801991980472;19994.60010799805;11961.700957476132;11979.04167666567;12036.63890083299;12059.939700301442;12092;13499.32503374847;13806.17162970225;13965.511207107702;13977;13995.16029038261;11194.440277985905;11556.191066625255;11558.826617600911;11627.709307267385;11761.05911527093;10967.670969870916;11344.319340839576;11388.886111138783;11420.200585958903;11587.478563464496;9779.706608801747;9877;9930.205583553154;9937.20502359795;9947.452890091114;6822.8635427292165;6930.13373328334;6966.850469672448;7023.192332881784;7068.646567671699;6928.16861976566;7212.783616491513;7216.061911951413;7307.7807665770115;7322.121345438582 15:39:10 INFO - PID 3781 | |4;prototype.html;493.23794737131107;504.9343585333906;522.7183344700379;533.4978948730454;537.7633841109912;6125.632462052275;6197.411245931635;6323.652199129057;6335.619862808235;6339.746410143594;6069.241344831896;6171.197744293247;6185.505159587234;6197.814065578029;6212.658303793301;5121.9487805122035;5140.665856719302;5143.485651434864;5173.706573356661;5180.2488639147305;5114;5145.974270128663;5148.613853960968;5151.304573882534;5166.07010738067;4745.857624271277;4841.443234028079;4851.660383773137;4905.950940490585;5024.623153263502;4817.807287708488;4864.7324397158145;4889.704228379483;4933.80264789408;4950.653454258203;4366.126774645084;4393.275109606911;4402.3836662867225;4426.822927082912;4453.821847126111;5811.593188476808;5995.700214989233;6009;6010.489108425789;6067.484263837579;5580.609357344987;5608.186812912105;5634.830955071355;5650.208970744099;5658.292713410824;6387.169667943184;6406.263279722845;6485;6503.512236582275;6519.641419721914;4503.301988191835;4773.284007398884;4776.4268287805335;4795.904081918342;4803.519648035169;6539.542232043759;6584.473242140617;6587.538872278942;6744.6627668616375;6798.830029249309;4760.809567617326;4783.689060211075;4791.568758811654;4814.675964109828;4846.739847639639;4858.611311095104;4882.26765985098;4894.1679914414635;4895.877603059881;4904.362432883774;5492.80775172867;5509.228707980886;5510.283663123768;5562.860928476821;5563.916541251884;6402.327755585597;6493.350664933563;6531.8693626127215;6542.280349161589;6560.934390656128;1714.2200298863986;1728.28276265351;1731.9913400432918;1734.2629382512382;1753.9826900397738;1123.8370436286693;1160.8490896183441;1177.587844254504;1178.8762179971152;1202.0864143251017;5512.602018122049;5512.6968016758665;5552.305961754781;5598.216249725042;5629.296337957755;6394.4564712;6411.647359395278;6506.210792032229;6520.576162549419;6535.2354864187355;5497.257870187474;5516.641418307878;5571.777128914879;5605.495505404533;5612.326520817446;3345.093296820789;3446.379651662675;3449.741269404805;3472.6874581287807;3509.982450087733;6250.3749625037135;6310.558260921692;6331.778387756406;6333.303336632966;6334.714937827809;6695.933040669628;6698.5980841149685;6705.362990515893;6745.2242992056545;6774.932250677529;6222.533310001678;6336.936630633634;6343.333949935282;6348.460380867586;6391.712372943229;6251.84252472101;6364.713587888464;6411.3909178627955;6532.444742196966;6540.542162048611;5855.648661080349;6304.7162877671535;6340.968295158679;6387.584806987531;6415.364082159206;6198.5825528045;6235.875282494234;6271.811845644638;6276.560640755103;6286.296892336157;4779.522047795332;4810.550241027106;4838.153323168361;4843.104025755247;4863.586595139453;6351.174347334817;6580.473562115115;6581.407673309425;6585.176852893388;6597.4392176663605;6256.436920677161;6351.841203969938;6411.903821442589;6420.165378500952;6422.454091402283;6154.446099850856;6365.4589359903075;6386.552941294065;6392.712327945346;6410.967945160244;6162.722677479434;6164;6329.461995730415;6339.429451349401;6343.809685709436;6379.947308694013;6383.648899310582;6384.489240860813;6437.935620643733;6445.6454894981225;6166.722497487714;6198;6230.875382492415;6237.594556353823;6270.435660790551 15:39:10 INFO - PID 3781 | |5;yui.html;907.7231444409455;925.0795458518775;933.3093510802005;938.4463166731626;939.9530023498826;17298.827011729896;17313.567160820985;17448.127593620316;17520.73718894216;17558.297668093262;16052;16542.255598498068;16873.40943066993;16937.576560585992;17055.232514536845;930.0838673906197;953.2993249961273;954.6420092465326;955.254901177083;958.3052287091838;952.3286083311249;964.8842138943337;966.4732720667254;968.5157421289356;969.8157350103457;1022.3814592171741;1024.5952848624827;1031.742064483879;1032.958681652733;1033.9534720937577;1035.6582327831836;1038.024257198235;1038.231708535678;1038.9688309350731;1039.215392378753;707.8831992721194;727.2472990454906;730.5543618392776;734.7795661301589;752.0261261666101;17870.46388608344;18168.27326906922;18193.090345482804;18193.090345482804;18206.90896545522;20313.898430507754;20578;20707.689384659243;20737;20789.168433262803;15956.680866382723;16019;16020;16056.678866422722;16221.756673649905;3693.759905606154;3706.054955986206;3713.220223753015;3738.925221495555;3744.2511497700298;129574;131791.34104329514;132116;133085.33457332748;133503;15852.841471585367;15870.127143007014;15915.363385464683;15999;16268.837311626969;26963.325916852235;27702.584461232695;27820.443591128063;27923.720762792113;27977.300567485974;8474.237318641352;8620.913790862136;8801.427907186013;8886.733397997941;8910.599023043978;3979.383195604686;4085.3259212230246;4129.710920235615;4129.814158362881;4146.647534959502;5273.367195936512;5279.39286982002;5327.7336133193185;5338.759755810997;5408.080626293539;964.0793042644258;969.60730903984;991.6430085169334;1002.5388321372104;1003.9046290602381;665.1785045468843;677.549429629291;698.1412862179548;705.9611721355375;708.288170388756;10526.578936842594;10555.261131720707;11190.440477976068;11295.322280663186;11868.287902725691;680.5032326401755;683.9179298484214;695.3359541637724;703.774792066544;705.6189657584847;5334.3865455473115;5363.624546281801;5459.454054594509;5481.643693159932;5588.357338905997;118202.40898795622;119589;119910.4004479972;124013;126445;103000.48499757453;105087;105719;106703.46648266709;107093;89256.5537172323;92066;92584.53707731418;94092.52953735187;96848.0315196853;10028.749281268027;10115.14021308197;10236.846447303147;10262.948685256526;10416.687499375032;42994.35508467313;44438.555614443445;44586;44734;45163.09673806571;56025.43974560202;57305;57574;58020.129698053715;58593;15009.32458039369;15324.463643772415;15412.845871541142;15460.613484662974;15630;16692.24884880159;16904.57738556546;16969.23638436298;17213;17482.562935926704;14825.184614846286;14854.257287135817;14963.40146394132;15029.699406012038;15310.464133755266;14143.787843182567;14247.715045699235;14329.64175895611;14392.06451580644;14595.416183352547;13911.930440347733;14059.086159399607;14230.644233894236;14238.644033899236;14850.108993460426;11177.608783692533;11284.43577821122;11386.772264554827;11417.200795944203;11658.53365865356;11420.143489238175;11484.94257528707;11550.249233799776;11589;11750.059995200534 15:39:10 INFO - PID 3781 | __end_tp_report 15:39:10 INFO - PID 3781 | __start_cc_report 15:39:10 INFO - PID 3781 | _x_x_mozilla_cycle_collect,272 15:39:10 INFO - PID 3781 | __end_cc_report 15:39:10 INFO - PID 3781 | __startTimestamp1505687950042__endTimestamp 15:39:10 INFO - PID 3781 | 15:39:10 INFO - PID 3781 | ------- Summary: start ------- 15:39:10 INFO - PID 3781 | Number of tests: 6 15:39:10 INFO - PID 3781 | 15:39:10 INFO - PID 3781 | [#0] dojo.html Cycles:165 Average:6130.90 Median:6372.94 stddev:727.31 (11.4%) stddev-sans-first:689.63 15:39:10 INFO - PID 3781 | Values: 3102.0 3115.2 3139.5 3152.0 3158.5 6045.1 6203.4 6221.8 6244.5 6339.0 6137.9 6148.3 6186.1 6189.7 6244.4 5712.5 5716.2 5731.5 5738.7 5787.9 5821.4 5828.4 5858.5 5859.7 5915.4 5779.4 5780.7 5789.2 5794.7 5883.3 5297.8 5556.5 5621.2 5645.6 5652.6 5691.1 5711.3 5730.7 5736.7 5752.6 6060.3 6216.5 6242.1 6274.7 6296.2 6547.9 6553.2 6583.1 6601.4 6602.0 6611.4 6636.4 6667.9 6671.3 6681.5 6126.9 6183.2 6241.0 6248.7 6307.0 6814.1 6897.4 6947.8 6957.0 6984.4 6344.9 6601.2 6602.0 6663.0 6697.7 6545.3 6619.9 6687.7 6722.3 6753.5 6628.2 6707.9 6716.2 6735.6 6742.1 6698.6 6720.0 6785.4 6794.7 6796.8 5261.3 5270.8 5287.9 5291.4 5299.1 4626.9 4632.7 4638.3 4693.4 4721.5 5696.3 5746.7 5769.7 5782.1 5808.9 6521.3 6623.0 6641.4 6707.4 6719.5 5542.4 5692.5 5694.4 5709.8 5790.4 5705.9 5790.9 5802.2 5803.3 5803.4 6476.2 6486.1 6554.8 6562.1 6579.1 6486.8 6567.5 6621.2 6629.4 6651.3 6294.5 6366.5 6369.0 6372.9 6382.0 6471.3 6482.4 6531.0 6533.7 6609.8 6709.4 6717.7 6741.3 6789.3 6805.9 6401.6 6464.5 6486.5 6505.5 6531.1 6273.1 6420.8 6458.2 6484.6 6528.3 6379.4 6398.4 6438.1 6464.0 6476.7 6438.2 6502.0 6566.5 6575.7 6604.1 6268.1 6315.7 6401.6 6404.3 6409.0 15:39:10 INFO - PID 3781 | 15:39:10 INFO - PID 3781 | [#1] ext.html Cycles:180 Average:36229.71 Median:34021.99 stddev:52095.66 (153.1%) stddev-sans-first:52174.08 15:39:10 INFO - PID 3781 | Values: 850.9 855.8 857.1 864.5 889.9 37831.6 38937.2 39210.4 39304.2 39383.6 35948.3 36627.6 36678.4 36704.0 36792.6 6878.8 7001.4 7004.1 7020.2 7021.1 6751.5 6822.0 6845.3 6855.7 6861.8 6830.5 6844.6 7521.2 7747.3 7769.5 7650.6 7803.3 7808.1 7810.4 7817.6 7316.0 7633.8 7670.4 7718.4 7730.3 36217.5 36903.3 36918.0 36921.8 37003.4 3488.4 3492.1 3507.7 3508.2 3508.6 30179.8 30343.2 30393.8 30400.7 30615.2 5071.3 5136.1 5155.7 5175.7 5259.0 176410.0 180231.0 189908.0 197573.0 197680.0 45556.5 45928.0 46038.8 46300.8 46311.1 47081.3 47100.8 47104.0 47138.8 47461.5 15421.5 15446.7 15537.8 15847.1 15904.3 13133.6 13265.5 13271.7 13275.3 13284.8 13849.7 14020.9 14027.3 14030.3 14160.0 5757.6 5804.7 5870.6 5879.0 5896.5 971.9 996.6 1004.5 1008.9 1017.2 22345.0 22398.8 22521.5 22801.9 22859.0 1080.6 1109.6 1119.8 1120.2 1128.5 12007.4 12091.5 12213.3 12214.5 12243.9 223170.0 223652.0 224284.0 224316.0 224907.0 49765.3 50005.7 50144.7 50427.2 50437.5 1364.5 1443.2 1451.0 1457.5 1458.2 21788.9 21809.2 22057.9 22151.9 22295.8 35517.8 35519.3 39899.2 40915.0 45926.0 188143.0 188366.0 188980.0 189137.0 190704.0 32207.8 32548.8 32707.0 32826.0 32892.7 41002.6 41036.8 41164.4 41605.4 41846.6 30444.0 30663.7 30683.8 30855.7 31260.8 30312.1 30675.0 30871.7 30942.2 31349.8 33565.7 33735.7 34008.7 34035.3 34143.5 29176.0 29527.6 29898.1 30210.1 30424.5 22483.2 22538.0 22700.5 22868.4 23208.7 15:39:10 INFO - PID 3781 | 15:39:10 INFO - PID 3781 | [#2] jquery.html Cycles:180 Average:33266.60 Median:22477.22 stddev:77759.58 (345.9%) stddev-sans-first:77938.93 15:39:10 INFO - PID 3781 | Values: 552.3 552.5 558.4 560.0 569.7 21521.0 22443.8 22510.7 22553.7 22628.9 23743.9 24771.8 25061.2 25366.6 25405.0 6354.2 6526.7 6565.6 6602.3 6606.6 6463.3 6499.6 6515.4 6554.9 6555.3 6410.6 6509.0 6528.2 6529.1 6669.9 6611.5 6644.6 6648.6 6651.9 6653.4 6087.4 6090.0 6104.4 6121.5 6149.9 27515.3 28468.6 28611.6 28646.1 28776.6 1946.2 2004.8 2015.9 2024.6 2034.4 18570.8 19286.5 19531.5 19669.7 19700.7 2415.7 2475.1 2499.5 2501.9 2506.6 118566.0 123994.4 141626.0 145424.3 145977.3 14299.3 14364.6 14615.9 14684.7 14708.6 22177.8 22200.3 22279.2 22322.3 22358.0 9764.1 9991.1 9991.7 10002.9 10240.1 9203.2 9281.6 9294.4 9323.4 9396.2 8799.1 8850.4 8890.0 8991.4 9029.5 2580.7 2581.7 2620.6 2627.7 2689.0 960.6 975.7 978.5 983.2 986.0 18765.7 18822.9 18950.2 19423.2 19544.0 1025.6 1030.4 1034.1 1038.1 1048.9 10792.6 10794.5 10921.7 10921.9 10948.2 451682.0 459468.0 462297.0 464640.0 465430.0 106797.0 107184.5 107265.5 107856.0 108661.0 1196.4 1206.8 1220.1 1223.1 1223.3 17495.9 17639.2 17642.2 17755.3 17872.0 55208.0 55717.4 55848.4 55884.7 57014.7 65711.0 66350.7 66726.0 68411.7 69517.3 13577.7 14090.4 14174.0 14191.2 14240.3 22178.1 22387.2 22538.9 22619.5 23009.7 17323.5 17330.4 17495.6 17508.9 17545.6 15662.6 16324.9 16443.3 16724.7 16838.7 17542.6 17608.4 17996.6 18094.8 18346.4 13786.5 13793.5 13838.0 13916.2 14024.1 17724.6 17750.1 17888.4 17970.5 18007.8 15:39:10 INFO - PID 3781 | 15:39:10 INFO - PID 3781 | [#3] mootools.html Cycles:180 Average:9373.79 Median:2615.73 stddev:6767.78 (258.7%) stddev-sans-first:6759.63 15:39:10 INFO - PID 3781 | Values: 1307.7 1362.3 1369.1 1376.0 1377.2 15100.8 15556.2 15673.9 15674.8 15695.7 15338.8 15468.3 15513.8 15542.4 15601.0 2494.1 2591.2 2592.1 2595.6 2596.7 2677.2 2746.1 2751.6 2760.9 2761.4 2648.8 2663.5 2704.1 2745.1 2749.1 2637.2 2643.8 2664.3 2670.8 2671.5 2547.5 2598.2 2608.8 2622.7 2632.4 14938.6 15246.3 15248.8 15255.8 15291.5 7768.1 8035.7 8039.3 8040.7 8063.4 5094.8 5209.8 5398.5 5421.2 5431.7 1939.4 1951.5 1970.9 1986.2 2007.9 23230.0 24208.2 25813.4 26223.7 26232.9 6922.8 7036.4 7094.9 7239.2 7245.5 19817.4 19872.6 19913.3 20045.7 20061.2 8012.2 8151.4 8299.8 8304.6 8306.7 7140.3 7382.8 7384.4 7462.7 7506.3 4977.8 5069.7 5106.1 5140.7 5192.5 1718.4 1719.1 1727.5 1729.4 1735.8 868.4 870.4 892.6 896.2 897.6 7325.6 7388.9 7403.8 7409.4 7507.2 685.3 686.2 693.7 705.6 711.6 4251.8 4392.4 4418.9 4437.0 4440.6 20598.9 21010.2 21832.6 21885.2 21896.6 21406.4 21482.5 21487.0 21640.1 21719.9 10417.9 11305.1 11336.9 11366.2 11440.7 7812.3 7818.5 7892.1 7912.2 7962.6 18140.5 18598.7 18651.3 18963.8 19426.5 19507.4 19619.7 19724.3 19800.8 19994.6 11961.7 11979.0 12036.6 12059.9 12092.0 13499.3 13806.2 13965.5 13977.0 13995.2 11194.4 11556.2 11558.8 11627.7 11761.1 10967.7 11344.3 11388.9 11420.2 11587.5 9779.7 9877.0 9930.2 9937.2 9947.5 6822.9 6930.1 6966.9 7023.2 7068.6 6928.2 7212.8 7216.1 7307.8 7322.1 15:39:10 INFO - PID 3781 | 15:39:10 INFO - PID 3781 | [#4] prototype.html Cycles:180 Average:5383.48 Median:6068.36 stddev:1500.41 (24.7%) stddev-sans-first:1459.03 15:39:10 INFO - PID 3781 | Values: 493.2 504.9 522.7 533.5 537.8 6125.6 6197.4 6323.7 6335.6 6339.7 6069.2 6171.2 6185.5 6197.8 6212.7 5121.9 5140.7 5143.5 5173.7 5180.2 5114.0 5146.0 5148.6 5151.3 5166.1 4745.9 4841.4 4851.7 4906.0 5024.6 4817.8 4864.7 4889.7 4933.8 4950.7 4366.1 4393.3 4402.4 4426.8 4453.8 5811.6 5995.7 6009.0 6010.5 6067.5 5580.6 5608.2 5634.8 5650.2 5658.3 6387.2 6406.3 6485.0 6503.5 6519.6 4503.3 4773.3 4776.4 4795.9 4803.5 6539.5 6584.5 6587.5 6744.7 6798.8 4760.8 4783.7 4791.6 4814.7 4846.7 4858.6 4882.3 4894.2 4895.9 4904.4 5492.8 5509.2 5510.3 5562.9 5563.9 6402.3 6493.4 6531.9 6542.3 6560.9 1714.2 1728.3 1732.0 1734.3 1754.0 1123.8 1160.8 1177.6 1178.9 1202.1 5512.6 5512.7 5552.3 5598.2 5629.3 6394.5 6411.6 6506.2 6520.6 6535.2 5497.3 5516.6 5571.8 5605.5 5612.3 3345.1 3446.4 3449.7 3472.7 3510.0 6250.4 6310.6 6331.8 6333.3 6334.7 6695.9 6698.6 6705.4 6745.2 6774.9 6222.5 6336.9 6343.3 6348.5 6391.7 6251.8 6364.7 6411.4 6532.4 6540.5 5855.6 6304.7 6341.0 6387.6 6415.4 6198.6 6235.9 6271.8 6276.6 6286.3 4779.5 4810.6 4838.2 4843.1 4863.6 6351.2 6580.5 6581.4 6585.2 6597.4 6256.4 6351.8 6411.9 6420.2 6422.5 6154.4 6365.5 6386.6 6392.7 6411.0 6162.7 6164.0 6329.5 6339.4 6343.8 6379.9 6383.6 6384.5 6437.9 6445.6 6166.7 6198.0 6230.9 6237.6 6270.4 15:39:10 INFO - PID 3781 | 15:39:10 INFO - PID 3781 | [#5] yui.html Cycles:180 Average:23394.52 Median:17539.52 stddev:34265.87 (195.4%) stddev-sans-first:34320.40 15:39:10 INFO - PID 3781 | Values: 907.7 925.1 933.3 938.4 940.0 17298.8 17313.6 17448.1 17520.7 17558.3 16052.0 16542.3 16873.4 16937.6 17055.2 930.1 953.3 954.6 955.3 958.3 952.3 964.9 966.5 968.5 969.8 1022.4 1024.6 1031.7 1033.0 1034.0 1035.7 1038.0 1038.2 1039.0 1039.2 707.9 727.2 730.6 734.8 752.0 17870.5 18168.3 18193.1 18193.1 18206.9 20313.9 20578.0 20707.7 20737.0 20789.2 15956.7 16019.0 16020.0 16056.7 16221.8 3693.8 3706.1 3713.2 3738.9 3744.3 129574.0 131791.3 132116.0 133085.3 133503.0 15852.8 15870.1 15915.4 15999.0 16268.8 26963.3 27702.6 27820.4 27923.7 27977.3 8474.2 8620.9 8801.4 8886.7 8910.6 3979.4 4085.3 4129.7 4129.8 4146.6 5273.4 5279.4 5327.7 5338.8 5408.1 964.1 969.6 991.6 1002.5 1003.9 665.2 677.5 698.1 706.0 708.3 10526.6 10555.3 11190.4 11295.3 11868.3 680.5 683.9 695.3 703.8 705.6 5334.4 5363.6 5459.5 5481.6 5588.4 118202.4 119589.0 119910.4 124013.0 126445.0 103000.5 105087.0 105719.0 106703.5 107093.0 89256.6 92066.0 92584.5 94092.5 96848.0 10028.7 10115.1 10236.8 10262.9 10416.7 42994.4 44438.6 44586.0 44734.0 45163.1 56025.4 57305.0 57574.0 58020.1 58593.0 15009.3 15324.5 15412.8 15460.6 15630.0 16692.2 16904.6 16969.2 17213.0 17482.6 14825.2 14854.3 14963.4 15029.7 15310.5 14143.8 14247.7 14329.6 14392.1 14595.4 13911.9 14059.1 14230.6 14238.6 14850.1 11177.6 11284.4 11386.8 11417.2 11658.5 11420.1 11484.9 11550.2 11589.0 11750.1 15:39:10 INFO - PID 3781 | -------- Summary: end -------- 15:39:10 INFO - PID 3781 | 15:39:10 INFO - TEST-INFO | 3781: exit 0 15:39:11 INFO - TEST-OK | dromaeo_css | took 1114471ms 15:39:11 INFO - TEST-START | kraken 15:39:11 INFO - Initialising browser for kraken test... 15:39:11 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57854/getInfo.html -profile /tmp/tmpPWEECf/profile 15:39:11 INFO - TEST-INFO | started process 4162 (/builds/slave/test/build/application/firefox/firefox http://localhost:57854/getInfo.html) 15:39:16 INFO - TEST-INFO | 4162: exit 0 15:39:16 INFO - Browser initialized. 15:39:16 INFO - Running cycle 1/1 for kraken test... 15:39:16 INFO - Using env: {'DISPLAY': ':0', 15:39:16 INFO - 'HOME': '/home/cltbld', 15:39:16 INFO - 'JSGC_DISABLE_POISONING': '1', 15:39:16 INFO - 'LANG': 'en_US.UTF-8', 15:39:16 INFO - 'LANGUAGE': 'en_US:en', 15:39:16 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:39:16 INFO - 'LOGNAME': 'cltbld', 15:39:16 INFO - 'MAIL': '/var/mail/cltbld', 15:39:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:39:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:39:16 INFO - 'MOZ_ACCELERATED': '1', 15:39:16 INFO - 'MOZ_CRASHREPORTER': '1', 15:39:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:16 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:39:16 INFO - 'MOZ_NO_REMOTE': '1', 15:39:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:39:16 INFO - 'MOZ_WEBRENDER': '1', 15:39:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:16 INFO - 'NO_EM_RESTART': '1', 15:39:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:16 INFO - 'PWD': '/builds/slave/test', 15:39:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:39:16 INFO - 'RUST_BACKTRACE': 'full', 15:39:16 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:39:16 INFO - 'SHELL': '/bin/bash', 15:39:16 INFO - 'SHLVL': '1', 15:39:16 INFO - 'TERM': 'linux', 15:39:16 INFO - 'TMOUT': '86400', 15:39:16 INFO - 'USER': 'cltbld', 15:39:16 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891', 15:39:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:16 INFO - '_': '/tools/buildbot/bin/python'} 15:39:16 INFO - TEST-INFO | started process 4405 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPWEECf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:39:17 INFO - PID 4405 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:39:17 INFO - PID 4405 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:39:18 INFO - PID 4405 | 15:39:18 INFO - PID 4405 | (/builds/slave/test/build/application/firefox/firefox:4464): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:39:18 INFO - PID 4405 | 15:39:28 INFO - PID 4405 | RSS: Main: 211136512 15:39:28 INFO - PID 4405 | 15:39:31 INFO - PID 4405 | Cycle 1(1): loaded http://localhost:57854/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57854/tests/kraken/driver.html?testName=audio-beat-detection) 15:39:32 INFO - PID 4405 | RSS: Main: 208936960 15:39:32 INFO - PID 4405 | 15:39:34 INFO - PID 4405 | Cycle 1(1): loaded http://localhost:57854/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57854/tests/kraken/driver.html?testName=audio-dft) 15:39:35 INFO - PID 4405 | RSS: Main: 209514496 15:39:35 INFO - PID 4405 | 15:39:38 INFO - PID 4405 | Cycle 1(1): loaded http://localhost:57854/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57854/tests/kraken/driver.html?testName=audio-fft) 15:39:39 INFO - PID 4405 | RSS: Main: 206102528 15:39:39 INFO - PID 4405 | 15:39:41 INFO - PID 4405 | Cycle 1(1): loaded http://localhost:57854/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57854/tests/kraken/driver.html?testName=audio-oscillator) 15:39:41 INFO - PID 4405 | RSS: Main: 206450688 15:39:41 INFO - PID 4405 | 15:39:44 INFO - PID 4405 | Cycle 1(1): loaded http://localhost:57854/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57854/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:39:44 INFO - PID 4405 | RSS: Main: 207880192 15:39:44 INFO - PID 4405 | 15:39:48 INFO - PID 4405 | Cycle 1(1): loaded http://localhost:57854/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57854/tests/kraken/driver.html?testName=imaging-darkroom) 15:39:48 INFO - PID 4405 | RSS: Main: 209006592 15:39:48 INFO - PID 4405 | 15:39:52 INFO - PID 4405 | Cycle 1(1): loaded http://localhost:57854/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57854/tests/kraken/driver.html?testName=imaging-desaturate) 15:39:52 INFO - PID 4405 | RSS: Main: 208830464 15:39:52 INFO - PID 4405 | 15:39:56 INFO - PID 4405 | Cycle 1(1): loaded http://localhost:57854/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57854/tests/kraken/driver.html?testName=json-parse-financial) 15:39:56 INFO - PID 4405 | RSS: Main: 210632704 15:39:56 INFO - PID 4405 | 15:39:58 INFO - PID 4405 | Cycle 1(1): loaded http://localhost:57854/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57854/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:39:58 INFO - PID 4405 | RSS: Main: 210321408 15:39:58 INFO - PID 4405 | 15:40:00 INFO - PID 4405 | Cycle 1(1): loaded http://localhost:57854/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57854/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:40:01 INFO - PID 4405 | RSS: Main: 207568896 15:40:01 INFO - PID 4405 | 15:40:03 INFO - PID 4405 | Cycle 1(1): loaded http://localhost:57854/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57854/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:40:03 INFO - PID 4405 | RSS: Main: 207339520 15:40:03 INFO - PID 4405 | 15:40:06 INFO - PID 4405 | Cycle 1(1): loaded http://localhost:57854/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57854/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:40:07 INFO - PID 4405 | RSS: Main: 207601664 15:40:07 INFO - PID 4405 | 15:40:10 INFO - PID 4405 | Cycle 1(1): loaded http://localhost:57854/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57854/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:40:10 INFO - PID 4405 | RSS: Main: 207540224 15:40:10 INFO - PID 4405 | 15:40:12 INFO - PID 4405 | Cycle 1(1): loaded http://localhost:57854/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57854/tests/kraken/driver.html?testName=ai-astar) 15:40:12 INFO - PID 4405 | RSS: Main: 209850368 15:40:12 INFO - PID 4405 | 15:40:12 INFO - PID 4405 | __start_tp_report 15:40:12 INFO - PID 4405 | _x_x_mozilla_page_load 15:40:12 INFO - PID 4405 | _x_x_mozilla_page_load_details 15:40:12 INFO - PID 4405 | |i|pagename|runs| 15:40:12 INFO - PID 4405 | |0;ai-astar;109;108;116;113;109;118;117;118;117;118 15:40:12 INFO - PID 4405 | |1;audio-beat-detection;125;117;122;122;119;123;125;115;122;123 15:40:12 INFO - PID 4405 | |2;audio-dft;171;164;170;171;168;172;164;166;168;164 15:40:12 INFO - PID 4405 | |3;audio-fft;76;76;84;74;76;74;80;86;77;84 15:40:12 INFO - PID 4405 | |4;audio-oscillator;83;82;79;79;86;78;82;85;85;78 15:40:12 INFO - PID 4405 | |5;imaging-gaussian-blur;114;117;114;115;121;123;115;116;116;115 15:40:12 INFO - PID 4405 | |6;imaging-darkroom;154;153;150;149;149;149;148;148;148;148 15:40:12 INFO - PID 4405 | |7;imaging-desaturate;95;98;94;95;94;95;95;96;94;95 15:40:12 INFO - PID 4405 | |8;json-parse-financial;55;58;52;52;54;53;54;65;52;54 15:40:12 INFO - PID 4405 | |9;json-stringify-tinderbox;45;43;45;45;38;39;45;47;41;39 15:40:12 INFO - PID 4405 | |10;stanford-crypto-aes;76;75;79;77;73;78;73;78;74;75 15:40:12 INFO - PID 4405 | |11;stanford-crypto-ccm;117;110;118;114;112;116;124;121;119;123 15:40:12 INFO - PID 4405 | |12;stanford-crypto-pbkdf2;180;170;177;178;171;173;169;175;172;173 15:40:12 INFO - PID 4405 | |13;stanford-crypto-sha256-iterative;67;65;68;68;64;65;65;66;65;66 15:40:12 INFO - PID 4405 | __end_tp_report 15:40:12 INFO - PID 4405 | __start_cc_report 15:40:12 INFO - PID 4405 | _x_x_mozilla_cycle_collect,742 15:40:12 INFO - PID 4405 | __end_cc_report 15:40:12 INFO - PID 4405 | __startTimestamp1505688012835__endTimestamp 15:40:12 INFO - PID 4405 | 15:40:12 INFO - PID 4405 | ------- Summary: start ------- 15:40:12 INFO - PID 4405 | Number of tests: 14 15:40:12 INFO - PID 4405 | 15:40:12 INFO - PID 4405 | [#0] ai-astar Cycles:10 Average:114.30 Median:117.00 stddev:4.16 (3.6%) stddev-sans-first:3.95 15:40:12 INFO - PID 4405 | Values: 109.0 108.0 116.0 113.0 109.0 118.0 117.0 118.0 117.0 118.0 15:40:12 INFO - PID 4405 | 15:40:12 INFO - PID 4405 | [#1] audio-beat-detection Cycles:10 Average:121.30 Median:122.50 stddev:3.30 (2.7%) stddev-sans-first:3.22 15:40:12 INFO - PID 4405 | Values: 125.0 117.0 122.0 122.0 119.0 123.0 125.0 115.0 122.0 123.0 15:40:12 INFO - PID 4405 | 15:40:12 INFO - PID 4405 | [#2] audio-dft Cycles:10 Average:167.80 Median:169.00 stddev:3.16 (1.9%) stddev-sans-first:3.13 15:40:12 INFO - PID 4405 | Values: 171.0 164.0 170.0 171.0 168.0 172.0 164.0 166.0 168.0 164.0 15:40:12 INFO - PID 4405 | 15:40:12 INFO - PID 4405 | [#3] audio-fft Cycles:10 Average:78.70 Median:78.50 stddev:4.47 (5.7%) stddev-sans-first:4.64 15:40:12 INFO - PID 4405 | Values: 76.0 76.0 84.0 74.0 76.0 74.0 80.0 86.0 77.0 84.0 15:40:12 INFO - PID 4405 | 15:40:12 INFO - PID 4405 | [#4] audio-oscillator Cycles:10 Average:81.70 Median:82.50 stddev:3.06 (3.7%) stddev-sans-first:3.21 15:40:12 INFO - PID 4405 | Values: 83.0 82.0 79.0 79.0 86.0 78.0 82.0 85.0 85.0 78.0 15:40:12 INFO - PID 4405 | 15:40:12 INFO - PID 4405 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:116.00 stddev:3.03 (2.6%) stddev-sans-first:3.06 15:40:12 INFO - PID 4405 | Values: 114.0 117.0 114.0 115.0 121.0 123.0 115.0 116.0 116.0 115.0 15:40:12 INFO - PID 4405 | 15:40:12 INFO - PID 4405 | [#6] imaging-darkroom Cycles:10 Average:149.60 Median:149.00 stddev:2.17 (1.5%) stddev-sans-first:1.62 15:40:12 INFO - PID 4405 | Values: 154.0 153.0 150.0 149.0 149.0 149.0 148.0 148.0 148.0 148.0 15:40:12 INFO - PID 4405 | 15:40:12 INFO - PID 4405 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:1.20 (1.3%) stddev-sans-first:1.27 15:40:12 INFO - PID 4405 | Values: 95.0 98.0 94.0 95.0 94.0 95.0 95.0 96.0 94.0 95.0 15:40:12 INFO - PID 4405 | 15:40:12 INFO - PID 4405 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:54.00 stddev:3.98 (7.4%) stddev-sans-first:4.23 15:40:12 INFO - PID 4405 | Values: 55.0 58.0 52.0 52.0 54.0 53.0 54.0 65.0 52.0 54.0 15:40:12 INFO - PID 4405 | 15:40:12 INFO - PID 4405 | [#9] json-stringify-tinderbox Cycles:10 Average:42.70 Median:45.00 stddev:3.20 (7.1%) stddev-sans-first:3.28 15:40:12 INFO - PID 4405 | Values: 45.0 43.0 45.0 45.0 38.0 39.0 45.0 47.0 41.0 39.0 15:40:12 INFO - PID 4405 | 15:40:12 INFO - PID 4405 | [#10] stanford-crypto-aes Cycles:10 Average:75.80 Median:76.50 stddev:2.15 (2.8%) stddev-sans-first:2.28 15:40:12 INFO - PID 4405 | Values: 76.0 75.0 79.0 77.0 73.0 78.0 73.0 78.0 74.0 75.0 15:40:12 INFO - PID 4405 | 15:40:12 INFO - PID 4405 | [#11] stanford-crypto-ccm Cycles:10 Average:117.40 Median:118.50 stddev:4.58 (3.9%) stddev-sans-first:4.85 15:40:12 INFO - PID 4405 | Values: 117.0 110.0 118.0 114.0 112.0 116.0 124.0 121.0 119.0 123.0 15:40:12 INFO - PID 4405 | 15:40:12 INFO - PID 4405 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.80 Median:174.00 stddev:3.61 (2.1%) stddev-sans-first:3.06 15:40:12 INFO - PID 4405 | Values: 180.0 170.0 177.0 178.0 171.0 173.0 169.0 175.0 172.0 173.0 15:40:12 INFO - PID 4405 | 15:40:12 INFO - PID 4405 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:1.37 (2.1%) stddev-sans-first:1.39 15:40:12 INFO - PID 4405 | Values: 67.0 65.0 68.0 68.0 64.0 65.0 65.0 66.0 65.0 66.0 15:40:12 INFO - PID 4405 | -------- Summary: end -------- 15:40:12 INFO - PID 4405 | 15:40:13 INFO - PID 4405 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 15:40:13 INFO - TEST-INFO | 4405: exit 0 15:40:13 INFO - TEST-OK | kraken | took 62732ms 15:40:13 INFO - SUITE-END | took 1177s 15:40:13 INFO - Completed test suite (00:19:37) 15:40:13 INFO - javascript benchmark 15:40:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8325.578393458221, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3102.0383681058875, 3115.205622566246, 3139.4819854723974, 3152.0, 3158.5104308832124, 6045.063015232635, 6203.41067598578, 6221.7822376216845, 6244.531660125492, 6338.9999999999945, 6137.877242455148, 6148.262208534982, 6186.072089186613, 6189.659568723719, 6244.375562443754, 5712.542996560275, 5716.1711551824965, 5731.512821410164, 5738.741756620962, 5787.855303617423, 5821.359650438449, 5828.446297601721, 5858.472737453629, 5859.736311865955, 5915.438033386821, 5779.393163717812, 5780.653160810344, 5789.189513468118, 5794.710264486759, 5883.264591926009, 5297.841064768063, 5556.472135147154, 5621.156826476062, 5645.633033852787, 5652.604317697763, 5691.06097493913, 5711.257536520226, 5730.713464326767, 5736.737917658109, 5752.597318187729, 6060.33336333003, 6216.471599914013, 6242.094896240065, 6274.749010039593, 6296.1500197473215, 6547.9345206547805, 6553.213614366259, 6583.07836902834, 6601.438877695402, 6602.0, 6611.371919667624, 6636.435902948255, 6667.899981500282, 6671.2995135510555, 6681.465482761368, 6126.8774624507705, 6183.165272688219, 6241.0, 6248.687565621746, 6306.990881458991, 6814.1482314710665, 6897.41371983377, 6947.79156625302, 6956.991236270713, 6984.4412447004115, 6344.936550634527, 6601.240857301472, 6602.0, 6663.0, 6697.665116744143, 6545.312742162099, 6619.933800662028, 6687.665616719145, 6722.327767223239, 6753.527253092335, 6628.23775265841, 6707.932920670828, 6716.194056713128, 6735.595864248161, 6742.089817874622, 6698.5980841149685, 6720.0, 6785.389314961677, 6794.728210871609, 6796.830079248059, 5261.263423120766, 5270.78916843322, 5287.867803304871, 5291.444398338196, 5299.099153143898, 4626.907461850744, 4632.656529606444, 4638.257878739453, 4693.413323334583, 4721.527847215251, 5696.259486266758, 5746.655200687888, 5769.653820770767, 5782.074868021096, 5808.854778630568, 6521.250056243498, 6622.966885165543, 6641.369069938348, 6707.3628005339415, 6719.496037797185, 5542.420410183078, 5692.544596432275, 5694.430556944272, 5709.800156994485, 5790.449907258803, 5705.857353566194, 5790.855228619318, 5802.245708057925, 5803.274590676166, 5803.448672376117, 6476.158099447136, 6486.091947127406, 6554.80335589933, 6562.146920900253, 6579.078928949951, 6486.772962946368, 6567.540272180901, 6621.238557565749, 6629.370209830058, 6651.301613330724, 6294.464970477561, 6366.4588509977175, 6368.968155159195, 6372.9362706374195, 6381.968090159519, 6471.320511346241, 6482.416582507597, 6531.0, 6533.705983230861, 6609.8017059486365, 6709.429698475684, 6717.664116794239, 6741.325867413219, 6789.287124852017, 6805.931940680728, 6401.55189136756, 6464.450521705519, 6486.5459417842185, 6505.51208659343, 6531.052997315458, 6273.059041143682, 6420.775272865428, 6458.192725909262, 6484.610923344614, 6528.281888992112, 6379.425851673372, 6398.360163983564, 6438.130852334875, 6464.0, 6476.737036278069, 6438.227412710505, 6501.9674901625185, 6566.474682025331, 6575.704093315717, 6604.14146160996, 6268.091126786681, 6315.684215789284, 6401.583897046677, 6404.295527492065, 6409.0], "value": 6076.075424163703, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [850.8808766772651, 855.7732200966741, 857.1257317536114, 864.5331520978668, 889.9332550058747, 37831.62168378315, 38937.22125557487, 39210.411843822294, 39304.213915721666, 39383.606163938384, 35948.281034379295, 36627.63372366276, 36678.449823252675, 36704.0, 36792.632073679255, 6878.82802929927, 7001.404880585131, 7004.089468369105, 7020.157581090276, 7021.122359705037, 6751.493637977147, 6822.0, 6845.315468453165, 6855.725770969155, 6861.759838405656, 6830.521863469559, 6844.555103918255, 7521.210272921347, 7747.2640099190485, 7769.533827970312, 7650.579218143, 7803.258690424401, 7808.102068262166, 7810.375169986388, 7817.609119544, 7315.9634201829185, 7633.847323053508, 7670.4247181461615, 7718.382529397691, 7730.304272615435, 36217.45673814895, 36903.26193476142, 36918.0, 36921.81539092314, 37003.4449483258, 3488.4244099723514, 3492.1269682579355, 3507.719382449398, 3508.245727168671, 3508.614052454228, 30179.849100754574, 30343.241418964702, 30393.848030759927, 30400.695993040008, 30615.234619134477, 5071.3407257056715, 5136.101182293084, 5155.71643559604, 5175.741212939338, 5258.97370513145, 176410.0, 180231.0, 189908.0, 197573.0, 197680.0, 45556.54443455589, 45928.0, 46038.769806151424, 46300.7684961573, 46311.073778524915, 47081.29378059332, 47100.7644961773, 47104.0, 47138.76430617825, 47461.52538474571, 15421.537353879401, 15446.691066178613, 15537.766933496006, 15847.128407937675, 15904.284307206204, 13133.60599182026, 13265.469381224835, 13271.66820829487, 13275.26986015759, 13284.800727989088, 13849.653758656113, 14020.85979140216, 14027.29863506841, 14030.298485075706, 14160.017597536353, 5757.596968212269, 5804.709764511757, 5870.618409803349, 5878.970605146947, 5896.469317761336, 971.9319647624599, 996.6461906023402, 1004.487711267259, 1008.8940661230612, 1017.165923942375, 22345.0, 22398.77601223967, 22521.549569008526, 22801.885990570274, 22859.0, 1080.5569716416232, 1109.6060898380958, 1119.776044791045, 1120.176670147441, 1128.5090985421368, 12007.399630018464, 12091.516339346503, 12213.314187706777, 12214.450349734283, 12243.877561224273, 223170.0, 223652.0, 224284.0, 224316.0, 224907.0, 49765.25352119721, 50005.74997124991, 50144.749276254115, 50427.24359134616, 50437.49562504328, 1364.522417154008, 1443.1557538839895, 1451.0350616839683, 1457.5263039512201, 1458.2344269258724, 21788.89105554462, 21809.23667671624, 22057.889710551346, 22151.889240553694, 22295.77704222937, 35517.82241088778, 35519.289614208086, 39899.2020159601, 40915.0, 45926.0, 188143.0, 188366.0, 188980.0, 189137.0, 190704.0, 32207.838960805046, 32548.83725581357, 32707.0, 32826.0, 32892.671073289916, 41002.58997409988, 41036.79481602573, 41164.38253426261, 41605.37591936184, 41846.58153418427, 30444.0, 30663.693363066082, 30683.846580766953, 30855.69144308528, 31260.843695781376, 30312.09063728092, 30675.0, 30871.69128308778, 30942.22644433907, 31349.8432507836, 33565.66434335626, 33735.662643374235, 34008.65991340055, 34035.319293614484, 34143.487847681805, 29176.0, 29527.557086643286, 29898.103056908327, 30210.09369718912, 30424.543631845983, 22483.21308754186, 22538.0, 22700.545989079794, 22868.4282892929, 23208.651870221624], "value": 16608.97156809376, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.3316786688107, 552.5331095224535, 558.3997203006769, 559.9944000559993, 569.7493103034665, 21521.0, 22443.775562244373, 22510.66234006491, 22553.661695074567, 22628.8868555657, 23743.881280593618, 24771.75228247717, 25061.24816255515, 25366.619500707504, 25405.0, 6354.173957385545, 6526.706298216592, 6565.573237739555, 6602.306757790435, 6606.570572912769, 6463.321351258121, 6499.577527460724, 6515.446187074104, 6554.901676474857, 6555.311692272314, 6410.647414392207, 6508.967455162718, 6528.249251336086, 6529.053287273343, 6669.899951500732, 6611.471082313404, 6644.634545100018, 6648.634325112117, 6651.93348066518, 6653.4344580710695, 6087.360827113133, 6090.0, 6104.420080092428, 6121.479674227651, 6149.877002459971, 27515.31211719723, 28468.572971405447, 28611.57082643762, 28646.140615781558, 28776.568351474743, 1946.240966023252, 2004.7894971028124, 2015.9294424695213, 2024.5647185854964, 2034.4201902457728, 18570.814291857045, 19286.517837054045, 19531.511712207168, 19669.704954425695, 19700.70448943267, 2415.7221919479134, 2475.10896077412, 2499.462615537668, 2501.862397568133, 2506.6490691303234, 118566.0, 123994.38002809929, 141626.0, 145424.272878636, 145977.27011364876, 14299.285035748379, 14364.640883977983, 14615.92692036533, 14684.706305873822, 14708.55874323772, 22177.77822221757, 22200.333989980325, 22279.220227291968, 22322.330330090124, 22358.0, 9764.121229089416, 9991.100800927952, 9991.650292239881, 10002.949985250027, 10240.078392944672, 9203.171714545722, 9281.62873485053, 9294.442333460014, 9323.393979391454, 9396.154346108882, 8799.076097009849, 8850.424722393023, 8890.0, 8991.370604057784, 9029.503377314311, 2580.7290234525103, 2581.70310414301, 2620.580707086895, 2627.710951795301, 2689.0, 960.6397600899662, 975.7169322341099, 978.4911845840123, 983.1937810994915, 986.0484632329836, 18765.7185142223, 18822.905885470485, 18950.24199032051, 19423.223071077282, 19544.02279886, 1025.5948900184385, 1030.407515678473, 1034.0848349210853, 1038.117600039975, 1048.8531605575226, 10792.622258220925, 10794.514246858753, 10921.672349829518, 10921.890781092246, 10948.233623646269, 451682.0, 459468.0, 462297.0, 464640.0, 465430.0, 106797.0, 107184.4640776791, 107265.4636726827, 107856.0, 108661.0, 1196.4257156564724, 1206.849143857018, 1220.0666490135054, 1223.1071317937956, 1223.2660403757852, 17495.912520437316, 17639.206235719168, 17642.20610072524, 17755.289788408834, 17872.0, 55208.0, 55717.44282557123, 55848.44151558432, 55884.72057639686, 57014.714926425106, 65711.0, 66350.66824666038, 66726.0, 68411.65794170997, 69517.30482695108, 13577.660558485723, 14090.436382544583, 14174.0, 14191.219482928538, 14240.287985600471, 22178.1128754848, 22387.216447424264, 22538.887305563367, 22619.547609048055, 23009.65485517752, 17323.48029559065, 17330.39343622967, 17495.562610934827, 17508.912455437643, 17545.56136096608, 15662.608434789221, 16324.918375408048, 16443.342266309213, 16724.74912876332, 16838.747418788484, 17542.561435964202, 17608.38370657021, 17996.64006719832, 18094.81905180984, 18346.449606511826, 13786.517471888837, 13793.517226897011, 13838.0, 13916.234607096707, 14024.08843425174, 17724.645507090045, 17750.11249437497, 17888.373906913195, 17970.460886173434, 18007.81992180114], "value": 11726.599918370635, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1307.7057662026045, 1362.2575696245544, 1369.0827145812298, 1375.9656008599786, 1377.2081053394302, 15100.773488397668, 15556.222188890553, 15673.921630391833, 15674.843251567481, 15695.686086278281, 15338.846611533882, 15468.303926323342, 15513.767293490606, 15542.378304867792, 15601.0, 2494.114589320792, 2591.235585502272, 2592.053900326386, 2595.5976823592277, 2596.72734362892, 2677.1968409477176, 2746.148693904886, 2751.5735061065566, 2760.861956902157, 2761.4339060492653, 2648.7616114549687, 2663.533881570727, 2704.094128466956, 2745.052956729935, 2749.092799376211, 2637.1561100447866, 2643.841369517835, 2664.2806442260676, 2670.8263962842548, 2671.479061582992, 2547.541442540342, 2598.1815728045603, 2608.752168543985, 2622.6983896851916, 2632.3682316244153, 14938.62653433662, 15246.313915873812, 15248.847511524964, 15255.847441525553, 15291.541253762405, 7768.145503994556, 8035.718749843727, 8039.276465118168, 8040.718574849852, 8063.435559510836, 5094.79620815167, 5209.843704688866, 5398.4871437213405, 5421.159720243369, 5431.6740995540395, 1939.4084804134734, 1951.4535929939639, 1970.881747095179, 1986.1757370691146, 2007.9196832126697, 23230.0, 24208.1527146549, 25813.3546661335, 26223.73776262251, 26232.8688356557, 6922.788512010378, 7036.437085033185, 7094.92905070953, 7239.167495737952, 7245.492815502864, 19817.405477835688, 19872.60254794896, 19913.303034393728, 20045.699314510293, 20061.197552097754, 8012.238837310446, 8151.429399942066, 8299.83400332002, 8304.626291816761, 8306.70926517569, 7140.285971402818, 7382.778516644509, 7384.372328352043, 7462.738804141829, 7506.324430801144, 4977.751112444364, 5069.746512674352, 5106.080905436983, 5140.742962851842, 5192.4547922468355, 1718.3556166437586, 1719.1178446821464, 1727.464486009341, 1729.4119999200332, 1735.7830271216098, 868.370431437203, 870.4472659861034, 892.5983307511646, 896.2292428511475, 897.555709923579, 7325.633718314063, 7388.926110738824, 7403.778376567806, 7409.407247420193, 7507.21174276704, 685.266764561914, 686.1560280854477, 693.7121094745725, 705.5801797930224, 711.5872793779596, 4251.80866860992, 4392.38506609075, 4418.889527761831, 4437.0, 4440.578145076212, 20598.897005514875, 21010.159593616085, 21832.563348732936, 21885.234016809653, 21896.562068758536, 21406.357809265748, 21482.462938426663, 21487.0, 21640.134394624354, 21719.891400542896, 10417.854036056027, 11305.095592352756, 11336.94331528337, 11366.204365694322, 11440.713982150346, 7812.335951444191, 7818.491798033109, 7892.0924093729955, 7912.208779122042, 7962.641681124479, 18140.546486337946, 18598.721019184453, 18651.253949842398, 18963.810361896205, 19426.514337141685, 19507.414777556696, 19619.70570441473, 19724.30964916221, 19800.801991980472, 19994.60010799805, 11961.700957476132, 11979.04167666567, 12036.63890083299, 12059.939700301442, 12092.0, 13499.32503374847, 13806.17162970225, 13965.511207107702, 13977.0, 13995.16029038261, 11194.440277985905, 11556.191066625255, 11558.826617600911, 11627.709307267385, 11761.05911527093, 10967.670969870916, 11344.319340839576, 11388.886111138783, 11420.200585958903, 11587.478563464496, 9779.706608801747, 9877.0, 9930.205583553154, 9937.20502359795, 9947.452890091114, 6822.8635427292165, 6930.13373328334, 6966.850469672448, 7023.192332881784, 7068.646567671699, 6928.16861976566, 7212.783616491513, 7216.061911951413, 7307.7807665770115, 7322.121345438582], "value": 6611.954291246022, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.23794737131107, 504.9343585333906, 522.7183344700379, 533.4978948730454, 537.7633841109912, 6125.632462052275, 6197.411245931635, 6323.652199129057, 6335.619862808235, 6339.746410143594, 6069.241344831896, 6171.197744293247, 6185.505159587234, 6197.814065578029, 6212.658303793301, 5121.9487805122035, 5140.665856719302, 5143.485651434864, 5173.706573356661, 5180.2488639147305, 5114.0, 5145.974270128663, 5148.613853960968, 5151.304573882534, 5166.07010738067, 4745.857624271277, 4841.443234028079, 4851.660383773137, 4905.950940490585, 5024.623153263502, 4817.807287708488, 4864.7324397158145, 4889.704228379483, 4933.80264789408, 4950.653454258203, 4366.126774645084, 4393.275109606911, 4402.3836662867225, 4426.822927082912, 4453.821847126111, 5811.593188476808, 5995.700214989233, 6009.0, 6010.489108425789, 6067.484263837579, 5580.609357344987, 5608.186812912105, 5634.830955071355, 5650.208970744099, 5658.292713410824, 6387.169667943184, 6406.263279722845, 6485.0, 6503.512236582275, 6519.641419721914, 4503.301988191835, 4773.284007398884, 4776.4268287805335, 4795.904081918342, 4803.519648035169, 6539.542232043759, 6584.473242140617, 6587.538872278942, 6744.6627668616375, 6798.830029249309, 4760.809567617326, 4783.689060211075, 4791.568758811654, 4814.675964109828, 4846.739847639639, 4858.611311095104, 4882.26765985098, 4894.1679914414635, 4895.877603059881, 4904.362432883774, 5492.80775172867, 5509.228707980886, 5510.283663123768, 5562.860928476821, 5563.916541251884, 6402.327755585597, 6493.350664933563, 6531.8693626127215, 6542.280349161589, 6560.934390656128, 1714.2200298863986, 1728.28276265351, 1731.9913400432918, 1734.2629382512382, 1753.9826900397738, 1123.8370436286693, 1160.8490896183441, 1177.587844254504, 1178.8762179971152, 1202.0864143251017, 5512.602018122049, 5512.6968016758665, 5552.305961754781, 5598.216249725042, 5629.296337957755, 6394.4564712, 6411.647359395278, 6506.210792032229, 6520.576162549419, 6535.2354864187355, 5497.257870187474, 5516.641418307878, 5571.777128914879, 5605.495505404533, 5612.326520817446, 3345.093296820789, 3446.379651662675, 3449.741269404805, 3472.6874581287807, 3509.982450087733, 6250.3749625037135, 6310.558260921692, 6331.778387756406, 6333.303336632966, 6334.714937827809, 6695.933040669628, 6698.5980841149685, 6705.362990515893, 6745.2242992056545, 6774.932250677529, 6222.533310001678, 6336.936630633634, 6343.333949935282, 6348.460380867586, 6391.712372943229, 6251.84252472101, 6364.713587888464, 6411.3909178627955, 6532.444742196966, 6540.542162048611, 5855.648661080349, 6304.7162877671535, 6340.968295158679, 6387.584806987531, 6415.364082159206, 6198.5825528045, 6235.875282494234, 6271.811845644638, 6276.560640755103, 6286.296892336157, 4779.522047795332, 4810.550241027106, 4838.153323168361, 4843.104025755247, 4863.586595139453, 6351.174347334817, 6580.473562115115, 6581.407673309425, 6585.176852893388, 6597.4392176663605, 6256.436920677161, 6351.841203969938, 6411.903821442589, 6420.165378500952, 6422.454091402283, 6154.446099850856, 6365.4589359903075, 6386.552941294065, 6392.712327945346, 6410.967945160244, 6162.722677479434, 6164.0, 6329.461995730415, 6339.429451349401, 6343.809685709436, 6379.947308694013, 6383.648899310582, 6384.489240860813, 6437.935620643733, 6445.6454894981225, 6166.722497487714, 6198.0, 6230.875382492415, 6237.594556353823, 6270.435660790551], "value": 4943.804333072431, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [907.7231444409455, 925.0795458518775, 933.3093510802005, 938.4463166731626, 939.9530023498826, 17298.827011729896, 17313.567160820985, 17448.127593620316, 17520.73718894216, 17558.297668093262, 16052.0, 16542.255598498068, 16873.40943066993, 16937.576560585992, 17055.232514536845, 930.0838673906197, 953.2993249961273, 954.6420092465326, 955.254901177083, 958.3052287091838, 952.3286083311249, 964.8842138943337, 966.4732720667254, 968.5157421289356, 969.8157350103457, 1022.3814592171741, 1024.5952848624827, 1031.742064483879, 1032.958681652733, 1033.9534720937577, 1035.6582327831836, 1038.024257198235, 1038.231708535678, 1038.9688309350731, 1039.215392378753, 707.8831992721194, 727.2472990454906, 730.5543618392776, 734.7795661301589, 752.0261261666101, 17870.46388608344, 18168.27326906922, 18193.090345482804, 18193.090345482804, 18206.90896545522, 20313.898430507754, 20578.0, 20707.689384659243, 20737.0, 20789.168433262803, 15956.680866382723, 16019.0, 16020.0, 16056.678866422722, 16221.756673649905, 3693.759905606154, 3706.054955986206, 3713.220223753015, 3738.925221495555, 3744.2511497700298, 129574.0, 131791.34104329514, 132116.0, 133085.33457332748, 133503.0, 15852.841471585367, 15870.127143007014, 15915.363385464683, 15999.0, 16268.837311626969, 26963.325916852235, 27702.584461232695, 27820.443591128063, 27923.720762792113, 27977.300567485974, 8474.237318641352, 8620.913790862136, 8801.427907186013, 8886.733397997941, 8910.599023043978, 3979.383195604686, 4085.3259212230246, 4129.710920235615, 4129.814158362881, 4146.647534959502, 5273.367195936512, 5279.39286982002, 5327.7336133193185, 5338.759755810997, 5408.080626293539, 964.0793042644258, 969.60730903984, 991.6430085169334, 1002.5388321372104, 1003.9046290602381, 665.1785045468843, 677.549429629291, 698.1412862179548, 705.9611721355375, 708.288170388756, 10526.578936842594, 10555.261131720707, 11190.440477976068, 11295.322280663186, 11868.287902725691, 680.5032326401755, 683.9179298484214, 695.3359541637724, 703.774792066544, 705.6189657584847, 5334.3865455473115, 5363.624546281801, 5459.454054594509, 5481.643693159932, 5588.357338905997, 118202.40898795622, 119589.0, 119910.4004479972, 124013.0, 126445.0, 103000.48499757453, 105087.0, 105719.0, 106703.46648266709, 107093.0, 89256.5537172323, 92066.0, 92584.53707731418, 94092.52953735187, 96848.0315196853, 10028.749281268027, 10115.14021308197, 10236.846447303147, 10262.948685256526, 10416.687499375032, 42994.35508467313, 44438.555614443445, 44586.0, 44734.0, 45163.09673806571, 56025.43974560202, 57305.0, 57574.0, 58020.129698053715, 58593.0, 15009.32458039369, 15324.463643772415, 15412.845871541142, 15460.613484662974, 15630.0, 16692.24884880159, 16904.57738556546, 16969.23638436298, 17213.0, 17482.562935926704, 14825.184614846286, 14854.257287135817, 14963.40146394132, 15029.699406012038, 15310.464133755266, 14143.787843182567, 14247.715045699235, 14329.64175895611, 14392.06451580644, 14595.416183352547, 13911.930440347733, 14059.086159399607, 14230.644233894236, 14238.644033899236, 14850.108993460426, 11177.608783692533, 11284.43577821122, 11386.772264554827, 11417.200795944203, 11658.53365865356, 11420.143489238175, 11484.94257528707, 11550.249233799776, 11589.0, 11750.059995200534], "value": 8608.583887613779, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1455.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 108.0, 116.0, 113.0, 109.0, 118.0, 117.0, 118.0, 117.0, 118.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 117.0, 122.0, 122.0, 119.0, 123.0, 125.0, 115.0, 122.0, 123.0], "value": 121.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 164.0, 170.0, 171.0, 168.0, 172.0, 164.0, 166.0, 168.0, 164.0], "value": 167.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 84.0, 74.0, 76.0, 74.0, 80.0, 86.0, 77.0, 84.0], "value": 78.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 79.0, 79.0, 86.0, 78.0, 82.0, 85.0, 85.0, 78.0], "value": 81.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 117.0, 114.0, 115.0, 121.0, 123.0, 115.0, 116.0, 116.0, 115.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 153.0, 150.0, 149.0, 149.0, 149.0, 148.0, 148.0, 148.0, 148.0], "value": 149.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 98.0, 94.0, 95.0, 94.0, 95.0, 95.0, 96.0, 94.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 58.0, 52.0, 52.0, 54.0, 53.0, 54.0, 65.0, 52.0, 54.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 43.0, 45.0, 45.0, 38.0, 39.0, 45.0, 47.0, 41.0, 39.0], "value": 42.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 79.0, 77.0, 73.0, 78.0, 73.0, 78.0, 74.0, 75.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 110.0, 118.0, 114.0, 112.0, 116.0, 124.0, 121.0, 119.0, 123.0], "value": 117.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 170.0, 177.0, 178.0, 171.0, 173.0, 169.0, 175.0, 172.0, 173.0], "value": 173.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 68.0, 68.0, 64.0, 65.0, 65.0, 66.0, 65.0, 66.0], "value": 65.9, "unit": "score"}]}]} 15:40:14 INFO - Return code: 0 15:40:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:40:14 INFO - # TBPL SUCCESS # 15:40:14 INFO - Running post-action listener: _package_coverage_data 15:40:14 INFO - Running post-action listener: _resource_record_post_action 15:40:14 INFO - [mozharness: 2017-09-17 22:40:14.134704Z] Finished run-tests step (success) 15:40:14 INFO - Running post-run listener: _resource_record_post_run 15:40:14 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 19800064; Write bytes: 407801856; Read time: 9064; Write time: 253652 15:40:14 INFO - TinderboxPrint: CPU usage
13.3% 15:40:14 INFO - TinderboxPrint: I/O read bytes / time
19,800,064 / 9,064 15:40:14 INFO - TinderboxPrint: I/O write bytes / time
407,801,856 / 253,652 15:40:14 INFO - TinderboxPrint: CPU idle
8,265.2 (86.7%) 15:40:14 INFO - TinderboxPrint: CPU user
1,200.0 (12.6%) 15:40:14 INFO - TinderboxPrint: Swap in / out
0 / 0 15:40:14 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 175644672; Read time: 160; Write time: 150452 15:40:14 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:40:14 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 17575936; Write bytes: 229830656; Read time: 8768; Write time: 100144 15:40:14 INFO - Running post-run listener: _upload_blobber_files 15:40:14 INFO - Blob upload gear active. 15:40:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:40:14 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 15:40:14 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'] 15:40:14 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 15:40:15 INFO - (blobuploader) - INFO - Open directory for files ... 15:40:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:40:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:40:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:40:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:40:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:40:22 INFO - (blobuploader) - INFO - Done attempting. 15:40:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:40:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:40:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:40:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:40:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:40:23 INFO - (blobuploader) - INFO - Done attempting. 15:40:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:40:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:40:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:40:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:40:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:40:24 INFO - (blobuploader) - INFO - Done attempting. 15:40:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:40:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:40:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:40:25 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:40:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:40:25 INFO - (blobuploader) - INFO - Done attempting. 15:40:25 INFO - (blobuploader) - INFO - Iteration through files over. 15:40:25 INFO - Return code: 0 15:40:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:40:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:40:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/911c9e9e137da19a3d74ac9c2796e869401d3e105b84b5a43babb045ab331503f2f2810224d234d7e22eea1c55f59e0900812554345769aa37719784b09fa280", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c54bc5aa0b36ef53553d1e7d2a18ad9f86b4bbf2d5c78353ed16a2d395b575cb14d6c9acccef60c9b316c5cac6d966a4bc417acb4463db376fdc3b9f89e04660", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8b60794e45d3e16b1d2dec346a6eb633a07f9a9118d2b5b02830c5775a27a591e2cd24e0e51be7b69fb82292a18b4257ab54c3c4fce91a5d50d46d65203eaa6", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d641dced3b7428801f4cd917a2f817813176847e68d7aa5c5bc6b4ca58b4149925c238dbdc03f7ac2ee608af36a37eccceeaef765d2615ed4d46f9388c7578df"} 15:40:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:40:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:40:25 INFO - Contents: 15:40:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/911c9e9e137da19a3d74ac9c2796e869401d3e105b84b5a43babb045ab331503f2f2810224d234d7e22eea1c55f59e0900812554345769aa37719784b09fa280", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c54bc5aa0b36ef53553d1e7d2a18ad9f86b4bbf2d5c78353ed16a2d395b575cb14d6c9acccef60c9b316c5cac6d966a4bc417acb4463db376fdc3b9f89e04660", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8b60794e45d3e16b1d2dec346a6eb633a07f9a9118d2b5b02830c5775a27a591e2cd24e0e51be7b69fb82292a18b4257ab54c3c4fce91a5d50d46d65203eaa6", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d641dced3b7428801f4cd917a2f817813176847e68d7aa5c5bc6b4ca58b4149925c238dbdc03f7ac2ee608af36a37eccceeaef765d2615ed4d46f9388c7578df"} 15:40:25 INFO - Running post-run listener: copy_logs_to_upload_dir 15:40:25 INFO - Copying logs to upload dir... 15:40:25 INFO - mkdir: /builds/slave/test/build/upload/logs 15:40:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.198584 ========= master_lag: 0.24 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-09-17 15:40:25.719158) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-17 15:40:25.727673) ========= 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=9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/911c9e9e137da19a3d74ac9c2796e869401d3e105b84b5a43babb045ab331503f2f2810224d234d7e22eea1c55f59e0900812554345769aa37719784b09fa280", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c54bc5aa0b36ef53553d1e7d2a18ad9f86b4bbf2d5c78353ed16a2d395b575cb14d6c9acccef60c9b316c5cac6d966a4bc417acb4463db376fdc3b9f89e04660", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8b60794e45d3e16b1d2dec346a6eb633a07f9a9118d2b5b02830c5775a27a591e2cd24e0e51be7b69fb82292a18b4257ab54c3c4fce91a5d50d46d65203eaa6", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d641dced3b7428801f4cd917a2f817813176847e68d7aa5c5bc6b4ca58b4149925c238dbdc03f7ac2ee608af36a37eccceeaef765d2615ed4d46f9388c7578df"} build_url:https://queue.taskcluster.net/v1/task/Mt_FquQNTb2zZxQoyb7pNg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011228 build_url: 'https://queue.taskcluster.net/v1/task/Mt_FquQNTb2zZxQoyb7pNg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/911c9e9e137da19a3d74ac9c2796e869401d3e105b84b5a43babb045ab331503f2f2810224d234d7e22eea1c55f59e0900812554345769aa37719784b09fa280", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c54bc5aa0b36ef53553d1e7d2a18ad9f86b4bbf2d5c78353ed16a2d395b575cb14d6c9acccef60c9b316c5cac6d966a4bc417acb4463db376fdc3b9f89e04660", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8b60794e45d3e16b1d2dec346a6eb633a07f9a9118d2b5b02830c5775a27a591e2cd24e0e51be7b69fb82292a18b4257ab54c3c4fce91a5d50d46d65203eaa6", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d641dced3b7428801f4cd917a2f817813176847e68d7aa5c5bc6b4ca58b4149925c238dbdc03f7ac2ee608af36a37eccceeaef765d2615ed4d46f9388c7578df"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-17 15:40:25.760684) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 15:40:25.761035) ========= 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=9531f05ccb2eabcb9acf5f1b000001c0-1505685792.234912-882451891 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004452 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 15:40:25.841095) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-17 15:40:25.843434) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-17 15:40:25.843740) ========= ========= Total master_lag: 0.45 =========