builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-024 starttime: 1506595577.3 results: success (0) revision: 76a26ef7c493311c170ae83eb0c1d6592a21396d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:17.299040) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:17.299520) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:17.299800) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:17.311805) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:17.312180) ========= 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=41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-28 03:46:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 41.7M=0s 2017-09-28 03:46:17 (41.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113746 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:17.441888) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:17.442357) ========= 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=41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020722 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:17.492530) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-28 03:46:17.492910) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 76a26ef7c493311c170ae83eb0c1d6592a21396d --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 76a26ef7c493311c170ae83eb0c1d6592a21396d --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=41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-28 03:46:17,549 truncating revision to first 12 chars 2017-09-28 03:46:17,549 Setting DEBUG logging. 2017-09-28 03:46:17,549 attempt 1/10 2017-09-28 03:46:17,549 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/76a26ef7c493?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-28 03:46:18,432 unpacking tar archive at: mozilla-central-76a26ef7c493/testing/mozharness/ program finished with exit code 0 elapsedTime=1.205041 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-28 03:46:18.717110) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:18.717625) ========= script_repo_revision: 76a26ef7c493311c170ae83eb0c1d6592a21396d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:18.718218) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:18.718680) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:18.932813) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-09-28 03:46:18.933274) ========= /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=41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:46:19 INFO - MultiFileLogger online at 20170928 03:46:19 in /builds/slave/test 03:46:19 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 03:46:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:46:19 INFO - {'append_to_log': False, 03:46:19 INFO - 'base_work_dir': '/builds/slave/test', 03:46:19 INFO - 'blob_upload_branch': 'Firefox', 03:46:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:46:19 INFO - 'branch': 'Firefox', 03:46:19 INFO - 'buildbot_json_path': 'buildprops.json', 03:46:19 INFO - 'code_coverage': False, 03:46:19 INFO - 'config_files': ('talos/linux_config.py',), 03:46:19 INFO - 'default_actions': ('clobber', 03:46:19 INFO - 'read-buildbot-config', 03:46:19 INFO - 'download-and-extract', 03:46:19 INFO - 'populate-webroot', 03:46:19 INFO - 'create-virtualenv', 03:46:19 INFO - 'install', 03:46:19 INFO - 'setup-mitmproxy', 03:46:19 INFO - 'run-tests'), 03:46:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:46:19 INFO - 'disable_ccov_upload': False, 03:46:19 INFO - 'disable_stylo': False, 03:46:19 INFO - 'download_minidump_stackwalk': True, 03:46:19 INFO - 'download_symbols': 'ondemand', 03:46:19 INFO - 'enable_stylo': False, 03:46:19 INFO - 'enable_webrender': False, 03:46:19 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:46:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:46:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:46:19 INFO - 'gecko_profile': False, 03:46:19 INFO - 'gecko_profile_interval': 0, 03:46:19 INFO - 'installer_path': 'installer.exe', 03:46:19 INFO - 'log_level': 'info', 03:46:19 INFO - 'log_name': 'talos', 03:46:19 INFO - 'log_to_console': True, 03:46:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:46:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:46:19 INFO - 'opt_config_files': (), 03:46:19 INFO - 'pip_index': False, 03:46:19 INFO - 'suite': 'dromaeojs-e10s', 03:46:19 INFO - 'system_bits': '32', 03:46:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:46:19 INFO - 'title': 'talos-linux64-ix-024', 03:46:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:46:19 INFO - 'use_talos_json': True, 03:46:19 INFO - 'verify': 'False', 03:46:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:46:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:46:19 INFO - 'work_dir': 'build'} 03:46:19 INFO - [mozharness: 2017-09-28 10:46:19.125713Z] Running clobber step. 03:46:19 INFO - Running pre-action listener: _resource_record_pre_action 03:46:19 INFO - Running main action method: clobber 03:46:19 INFO - rmtree: /builds/slave/test/build 03:46:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:46:20 INFO - Running post-action listener: _resource_record_post_action 03:46:20 INFO - [mozharness: 2017-09-28 10:46:20.103725Z] Finished clobber step (success) 03:46:20 INFO - [mozharness: 2017-09-28 10:46:20.103824Z] Running read-buildbot-config step. 03:46:20 INFO - Running pre-action listener: _resource_record_pre_action 03:46:20 INFO - Running main action method: read_buildbot_config 03:46:20 INFO - Using buildbot properties: 03:46:20 INFO - { 03:46:20 INFO - "project": "", 03:46:20 INFO - "product": "firefox", 03:46:20 INFO - "who": "archaeopteryx@coole-files.de", 03:46:20 INFO - "installer_path": "public/build/target.tar.bz2", 03:46:20 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 03:46:20 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:46:20 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 03:46:20 INFO - "stage_platform": "linux64-qr", 03:46:20 INFO - "basedir": "/builds/slave/test", 03:46:20 INFO - "buildnumber": 92, 03:46:20 INFO - "platform": "ubuntu64_hw_qr", 03:46:20 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:46:20 INFO - "slavebuilddir": "test", 03:46:20 INFO - "taskId": "LpcsRRYpRxegWAD88eev8g", 03:46:20 INFO - "branch": "mozilla-central", 03:46:20 INFO - "script_repo_revision": "production", 03:46:20 INFO - "revision": "76a26ef7c493311c170ae83eb0c1d6592a21396d", 03:46:20 INFO - "slavename": "talos-linux64-ix-024", 03:46:20 INFO - "repo_path": "mozilla-central" 03:46:20 INFO - } 03:46:20 INFO - Finding installer, test and symbols from parent task. 03:46:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LpcsRRYpRxegWAD88eev8g'}, attempt #1 03:46:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LpcsRRYpRxegWAD88eev8g'}, attempt #1 03:46:20 INFO - Task dependencies: RdxJmsEUSFuRZWELzxBWSw 03:46:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw'}, attempt #1 03:46:21 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.tar.bz2 03:46:21 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.test_packages.json 03:46:21 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.crashreporter-symbols.zip 03:46:21 INFO - Running post-action listener: _resource_record_post_action 03:46:21 INFO - [mozharness: 2017-09-28 10:46:21.424679Z] Finished read-buildbot-config step (success) 03:46:21 INFO - [mozharness: 2017-09-28 10:46:21.424899Z] Running download-and-extract step. 03:46:21 INFO - Running pre-action listener: _resource_record_pre_action 03:46:21 INFO - Running main action method: download_and_extract 03:46:21 INFO - mkdir: /builds/slave/test/build/tests 03:46:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:21 INFO - trying https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.test_packages.json 03:46:21 INFO - Downloading https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:46:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:46:21 INFO - Downloaded 989 bytes. 03:46:21 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:46:21 INFO - Using the following test package requirements: 03:46:21 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:46:21 INFO - u'common': [u'target.common.tests.zip'], 03:46:21 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:46:21 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:46:21 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:46:21 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:46:21 INFO - u'mozbase': [u'target.common.tests.zip'], 03:46:21 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:46:21 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:46:21 INFO - u'web-platform': [u'target.common.tests.zip', 03:46:21 INFO - u'target.web-platform.tests.tar.gz'], 03:46:21 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:46:21 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:46:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.common.tests.zip 03:46:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:46:21 INFO - Fetch https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.common.tests.zip into memory 03:46:23 INFO - Content-Length response header: 55262973 03:46:23 INFO - Bytes received: 55262973 03:46:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.talos.tests.zip 03:46:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:46:33 INFO - Fetch https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.talos.tests.zip into memory 03:46:34 INFO - Content-Length response header: 14521162 03:46:34 INFO - Bytes received: 14521162 03:46:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:34 INFO - trying https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.tar.bz2 03:46:34 INFO - Downloading https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:46:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:46:35 INFO - Downloaded 61586385 bytes. 03:46:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.tar.bz2 03:46:35 INFO - mkdir: /builds/slave/test/properties 03:46:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:46:35 INFO - Writing to file /builds/slave/test/properties/build_url 03:46:35 INFO - Contents: 03:46:35 INFO - build_url:https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.tar.bz2 03:46:35 INFO - Running post-action listener: _resource_record_post_action 03:46:35 INFO - Running post-action listener: find_tests_for_verification 03:46:35 INFO - Running post-action listener: set_extra_try_arguments 03:46:35 INFO - [mozharness: 2017-09-28 10:46:35.837797Z] Finished download-and-extract step (success) 03:46:35 INFO - [mozharness: 2017-09-28 10:46:35.837950Z] Running populate-webroot step. 03:46:35 INFO - Running pre-action listener: _resource_record_pre_action 03:46:35 INFO - Running main action method: populate_webroot 03:46:35 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:46:35 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:35 INFO - 'tests': ['tresize', 03:46:35 INFO - 'tcanvasmark']}, 03:46:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:46:35 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:35 INFO - 'tests': ['dromaeo_css', 03:46:35 INFO - 'kraken']}, 03:46:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:46:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:46:35 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:46:35 INFO - 'talos_options': ['--disable-stylo'], 03:46:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:46:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:46:35 INFO - 'tests': ['damp', 'tps']}, 03:46:35 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:46:35 INFO - 'talos_options': ['--disable-stylo'], 03:46:35 INFO - 'tests': ['damp', 'tps']}, 03:46:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:46:35 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:35 INFO - 'tests': ['dromaeo_dom']}, 03:46:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:46:35 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:35 INFO - 'tests': ['basic_compositor_video', 03:46:35 INFO - 'glvideo']}, 03:46:35 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:46:35 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:46:35 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:46:35 INFO - 'talos_options': ['--disable-stylo'], 03:46:35 INFO - 'tests': ['ts_paint_webext', 03:46:35 INFO - 'tp5o_webext']}, 03:46:35 INFO - 'other-e10s': {'tests': ['a11yr', 03:46:35 INFO - 'ts_paint', 03:46:35 INFO - 'tpaint', 03:46:35 INFO - 'sessionrestore', 03:46:35 INFO - 'sessionrestore_many_windows', 03:46:35 INFO - 'sessionrestore_no_auto_restore', 03:46:35 INFO - 'tabpaint', 03:46:35 INFO - 'cpstartup']}, 03:46:35 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:35 INFO - 'tests': ['a11yr', 03:46:35 INFO - 'ts_paint', 03:46:35 INFO - 'tpaint', 03:46:35 INFO - 'sessionrestore', 03:46:35 INFO - 'sessionrestore_many_windows', 03:46:35 INFO - 'sessionrestore_no_auto_restore', 03:46:35 INFO - 'tabpaint', 03:46:35 INFO - 'cpstartup']}, 03:46:35 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 03:46:35 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 03:46:35 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:35 INFO - 'tests': ['perf_reftest_singletons']}, 03:46:35 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:35 INFO - 'tests': ['perf_reftest']}, 03:46:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:46:35 INFO - 'tsvgr_opacity', 03:46:35 INFO - 'tart', 03:46:35 INFO - 'tscrollx', 03:46:35 INFO - 'tsvg_static']}, 03:46:35 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:35 INFO - 'tests': ['tsvgx', 03:46:35 INFO - 'tsvgr_opacity', 03:46:35 INFO - 'tart', 03:46:35 INFO - 'tscrollx', 03:46:35 INFO - 'tsvg_static']}, 03:46:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:46:35 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:46:35 INFO - 'talos_options': ['--disable-stylo'], 03:46:35 INFO - 'tests': ['tp5o']}, 03:46:35 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:46:35 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:46:35 INFO - 'talos_options': ['--mitmproxy', 03:46:35 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:46:35 INFO - '--firstNonBlankPaint'], 03:46:35 INFO - 'tests': ['tp6_google', 03:46:35 INFO - 'tp6_youtube', 03:46:35 INFO - 'tp6_amazon', 03:46:35 INFO - 'tp6_facebook']}, 03:46:35 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:46:35 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:46:35 INFO - 'talos_options': ['--disable-stylo', 03:46:35 INFO - '--mitmproxy', 03:46:35 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:46:35 INFO - '--firstNonBlankPaint'], 03:46:35 INFO - 'tests': ['tp6_google', 03:46:35 INFO - 'tp6_youtube', 03:46:35 INFO - 'tp6_amazon', 03:46:35 INFO - 'tp6_facebook']}, 03:46:35 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:46:35 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:46:35 INFO - 'talos_options': ['--stylo-threads=1', 03:46:35 INFO - '--mitmproxy', 03:46:35 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:46:35 INFO - '--firstNonBlankPaint'], 03:46:35 INFO - 'tests': ['tp6_google', 03:46:35 INFO - 'tp6_youtube', 03:46:35 INFO - 'tp6_amazon', 03:46:35 INFO - 'tp6_facebook']}, 03:46:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:46:35 INFO - 'talos_options': ['--xperf_path', 03:46:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:46:35 INFO - 'tests': ['tp5n']}, 03:46:35 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:46:35 INFO - 'talos_options': ['--disable-stylo', 03:46:35 INFO - '--xperf_path', 03:46:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:46:35 INFO - 'tests': ['tp5n']}}} 03:46:35 INFO - Running post-action listener: _resource_record_post_action 03:46:35 INFO - [mozharness: 2017-09-28 10:46:35.856968Z] Finished populate-webroot step (success) 03:46:35 INFO - [mozharness: 2017-09-28 10:46:35.857074Z] Running create-virtualenv step. 03:46:35 INFO - Running pre-action listener: _resource_record_pre_action 03:46:35 INFO - Running main action method: create_virtualenv 03:46:35 INFO - Creating virtualenv /builds/slave/test/build/venv 03:46:35 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:46:35 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:46:35 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:46:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:46:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:46:36 INFO - Installing distribute.............................................................................................................................................................................................done. 03:46:36 INFO - Installing pip...............done. 03:46:36 INFO - Return code: 0 03:46:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:46:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:46:36 INFO - Reading from file tmpfile_stdout 03:46:36 INFO - Output received: 03:46:36 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:46:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:46: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')]} 03:46:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46: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')]} 03:46:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:36 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 0x15471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b49c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b1d60>, '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 0x18b2a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18b2ef0>, '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': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', '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 03:46:36 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 03:46:36 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 03:46:36 INFO - Using env: {'DISPLAY': ':0', 03:46:36 INFO - 'HOME': '/home/cltbld', 03:46:36 INFO - 'LANG': 'en_US.UTF-8', 03:46:36 INFO - 'LANGUAGE': 'en_US:en', 03:46:36 INFO - 'LOGNAME': 'cltbld', 03:46:36 INFO - 'MAIL': '/var/mail/cltbld', 03:46:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:36 INFO - 'MOZ_NO_REMOTE': '1', 03:46:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:36 INFO - 'NO_EM_RESTART': '1', 03:46:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:36 INFO - 'PWD': '/builds/slave/test', 03:46:36 INFO - 'SHELL': '/bin/bash', 03:46:36 INFO - 'SHLVL': '1', 03:46:36 INFO - 'TERM': 'linux', 03:46:36 INFO - 'TMOUT': '86400', 03:46:36 INFO - 'USER': 'cltbld', 03:46:36 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', 03:46:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:36 INFO - '_': '/tools/buildbot/bin/python'} 03:46:38 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:46:38 INFO - Downloading/unpacking pip>=1.5 03:46:38 INFO - Running setup.py egg_info for package pip 03:46:38 INFO - warning: no files found matching 'pip/cacert.pem' 03:46:38 INFO - warning: no files found matching '*.html' under directory 'docs' 03:46:38 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:46:38 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:46:38 INFO - Installing collected packages: pip 03:46:38 INFO - Found existing installation: pip 1.1 03:46:38 INFO - Uninstalling pip: 03:46:38 INFO - Successfully uninstalled pip 03:46:38 INFO - Running setup.py install for pip 03:46:38 INFO - warning: no files found matching 'pip/cacert.pem' 03:46:38 INFO - warning: no files found matching '*.html' under directory 'docs' 03:46:38 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:46:38 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:46:38 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:46:38 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:46:38 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:46:38 INFO - Successfully installed pip 03:46:38 INFO - Cleaning up... 03:46:38 INFO - Return code: 0 03:46:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:46:38 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')]} 03:46:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:38 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')]} 03:46:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:38 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 0x15471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b49c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b1d60>, '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 0x18b2a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18b2ef0>, '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': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', '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 03:46:38 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 03:46:38 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 03:46:38 INFO - Using env: {'DISPLAY': ':0', 03:46:38 INFO - 'HOME': '/home/cltbld', 03:46:38 INFO - 'LANG': 'en_US.UTF-8', 03:46:38 INFO - 'LANGUAGE': 'en_US:en', 03:46:38 INFO - 'LOGNAME': 'cltbld', 03:46:38 INFO - 'MAIL': '/var/mail/cltbld', 03:46:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:38 INFO - 'MOZ_NO_REMOTE': '1', 03:46:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:38 INFO - 'NO_EM_RESTART': '1', 03:46:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:38 INFO - 'PWD': '/builds/slave/test', 03:46:38 INFO - 'SHELL': '/bin/bash', 03:46:38 INFO - 'SHLVL': '1', 03:46:38 INFO - 'TERM': 'linux', 03:46:38 INFO - 'TMOUT': '86400', 03:46:38 INFO - 'USER': 'cltbld', 03:46:38 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', 03:46:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:38 INFO - '_': '/tools/buildbot/bin/python'} 03:46:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:38 INFO - Downloading/unpacking psutil>=3.1.1 03:46:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:46:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:46:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:46:39 INFO - Installing collected packages: psutil 03:46:39 INFO - Running setup.py install for psutil 03:46:39 INFO - building 'psutil._psutil_linux' extension 03:46:39 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 03:46:39 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 03:46:39 INFO - building 'psutil._psutil_posix' extension 03:46:39 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 03:46:39 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 03:46:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:46:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:46:39 INFO - Successfully installed psutil 03:46:39 INFO - Cleaning up... 03:46:40 INFO - Return code: 0 03:46:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:46:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b49c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b1d60>, '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 0x18b2a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18b2ef0>, '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': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', '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 03:46:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:46:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:46:40 INFO - Using env: {'DISPLAY': ':0', 03:46:40 INFO - 'HOME': '/home/cltbld', 03:46:40 INFO - 'LANG': 'en_US.UTF-8', 03:46:40 INFO - 'LANGUAGE': 'en_US:en', 03:46:40 INFO - 'LOGNAME': 'cltbld', 03:46:40 INFO - 'MAIL': '/var/mail/cltbld', 03:46:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:40 INFO - 'MOZ_NO_REMOTE': '1', 03:46:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:40 INFO - 'NO_EM_RESTART': '1', 03:46:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:40 INFO - 'PWD': '/builds/slave/test', 03:46:40 INFO - 'SHELL': '/bin/bash', 03:46:40 INFO - 'SHLVL': '1', 03:46:40 INFO - 'TERM': 'linux', 03:46:40 INFO - 'TMOUT': '86400', 03:46:40 INFO - 'USER': 'cltbld', 03:46:40 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', 03:46:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:40 INFO - '_': '/tools/buildbot/bin/python'} 03:46:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:46:40 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 03:46:40 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 03:46:40 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 03:46:40 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 03:46:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:46:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:46:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:46:41 INFO - Installing collected packages: mozsystemmonitor 03:46:41 INFO - Running setup.py install for mozsystemmonitor 03:46:41 INFO - Successfully installed mozsystemmonitor 03:46:41 INFO - Cleaning up... 03:46:42 INFO - Return code: 0 03:46:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:46:42 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')]} 03:46:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:42 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')]} 03:46:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:42 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 0x15471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b49c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b1d60>, '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 0x18b2a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18b2ef0>, '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': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', '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 03:46:42 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 03:46:42 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 03:46:42 INFO - Using env: {'DISPLAY': ':0', 03:46:42 INFO - 'HOME': '/home/cltbld', 03:46:42 INFO - 'LANG': 'en_US.UTF-8', 03:46:42 INFO - 'LANGUAGE': 'en_US:en', 03:46:42 INFO - 'LOGNAME': 'cltbld', 03:46:42 INFO - 'MAIL': '/var/mail/cltbld', 03:46:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:42 INFO - 'MOZ_NO_REMOTE': '1', 03:46:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:42 INFO - 'NO_EM_RESTART': '1', 03:46:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:42 INFO - 'PWD': '/builds/slave/test', 03:46:42 INFO - 'SHELL': '/bin/bash', 03:46:42 INFO - 'SHLVL': '1', 03:46:42 INFO - 'TERM': 'linux', 03:46:42 INFO - 'TMOUT': '86400', 03:46:42 INFO - 'USER': 'cltbld', 03:46:42 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', 03:46:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:42 INFO - '_': '/tools/buildbot/bin/python'} 03:46:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:42 INFO - Downloading/unpacking jsonschema==2.5.1 03:46:42 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 03:46:42 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 03:46:42 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 03:46:42 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 03:46:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:46:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:46:43 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 03:46:43 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 03:46:43 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 03:46:43 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 03:46:43 INFO - Downloading functools32-3.2.3-2.tar.gz 03:46:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:46:44 INFO - warning: no files found matching '*.txt' 03:46:44 INFO - no previously-included directories found matching 'build' 03:46:44 INFO - no previously-included directories found matching 'dist' 03:46:44 INFO - no previously-included directories found matching '.git*' 03:46:44 INFO - Installing collected packages: jsonschema, functools32 03:46:44 INFO - Running setup.py install for functools32 03:46:44 INFO - warning: no files found matching '*.txt' 03:46:44 INFO - no previously-included directories found matching 'build' 03:46:44 INFO - no previously-included directories found matching 'dist' 03:46:44 INFO - no previously-included directories found matching '.git*' 03:46:44 INFO - Successfully installed jsonschema functools32 03:46:44 INFO - Cleaning up... 03:46:44 INFO - Return code: 0 03:46:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:46:44 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')]} 03:46:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:44 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')]} 03:46:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:44 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 0x15471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b49c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b1d60>, '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 0x18b2a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18b2ef0>, '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': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', '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 03:46:44 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 03:46:44 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 03:46:44 INFO - Using env: {'DISPLAY': ':0', 03:46:44 INFO - 'HOME': '/home/cltbld', 03:46:44 INFO - 'LANG': 'en_US.UTF-8', 03:46:44 INFO - 'LANGUAGE': 'en_US:en', 03:46:44 INFO - 'LOGNAME': 'cltbld', 03:46:44 INFO - 'MAIL': '/var/mail/cltbld', 03:46:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:44 INFO - 'MOZ_NO_REMOTE': '1', 03:46:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:44 INFO - 'NO_EM_RESTART': '1', 03:46:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:44 INFO - 'PWD': '/builds/slave/test', 03:46:44 INFO - 'SHELL': '/bin/bash', 03:46:44 INFO - 'SHLVL': '1', 03:46:44 INFO - 'TERM': 'linux', 03:46:44 INFO - 'TMOUT': '86400', 03:46:44 INFO - 'USER': 'cltbld', 03:46:44 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', 03:46:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:44 INFO - '_': '/tools/buildbot/bin/python'} 03:46:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:46:44 INFO - Cleaning up... 03:46:44 INFO - Return code: 0 03:46:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:46:44 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')]} 03:46:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:44 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')]} 03:46:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:44 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 0x15471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b49c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b1d60>, '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 0x18b2a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18b2ef0>, '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': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', '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 03:46:44 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 03:46:44 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 03:46:44 INFO - Using env: {'DISPLAY': ':0', 03:46:44 INFO - 'HOME': '/home/cltbld', 03:46:44 INFO - 'LANG': 'en_US.UTF-8', 03:46:44 INFO - 'LANGUAGE': 'en_US:en', 03:46:44 INFO - 'LOGNAME': 'cltbld', 03:46:44 INFO - 'MAIL': '/var/mail/cltbld', 03:46:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:44 INFO - 'MOZ_NO_REMOTE': '1', 03:46:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:44 INFO - 'NO_EM_RESTART': '1', 03:46:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:44 INFO - 'PWD': '/builds/slave/test', 03:46:44 INFO - 'SHELL': '/bin/bash', 03:46:44 INFO - 'SHLVL': '1', 03:46:44 INFO - 'TERM': 'linux', 03:46:44 INFO - 'TMOUT': '86400', 03:46:44 INFO - 'USER': 'cltbld', 03:46:44 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', 03:46:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:44 INFO - '_': '/tools/buildbot/bin/python'} 03:46:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:44 INFO - Downloading/unpacking blobuploader==1.2.4 03:46:44 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 03:46:44 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 03:46:44 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 03:46:44 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 03:46:45 INFO - Downloading blobuploader-1.2.4.tar.gz 03:46:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:46:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:46:45 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 03:46:45 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 03:46:45 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 03:46:45 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 03:46:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:46:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:46:46 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 03:46:46 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 03:46:46 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 03:46:46 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 03:46:46 INFO - Downloading docopt-0.6.1.tar.gz 03:46:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:46:46 INFO - Installing collected packages: blobuploader, requests, docopt 03:46:46 INFO - Running setup.py install for blobuploader 03:46:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:46:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:46:46 INFO - Running setup.py install for requests 03:46:46 INFO - Running setup.py install for docopt 03:46:47 INFO - Successfully installed blobuploader requests docopt 03:46:47 INFO - Cleaning up... 03:46:47 INFO - Return code: 0 03:46:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:46:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:47 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 0x15471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b49c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b1d60>, '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 0x18b2a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18b2ef0>, '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': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', '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 03:46:47 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 03:46:47 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 03:46:47 INFO - Using env: {'DISPLAY': ':0', 03:46:47 INFO - 'HOME': '/home/cltbld', 03:46:47 INFO - 'LANG': 'en_US.UTF-8', 03:46:47 INFO - 'LANGUAGE': 'en_US:en', 03:46:47 INFO - 'LOGNAME': 'cltbld', 03:46:47 INFO - 'MAIL': '/var/mail/cltbld', 03:46:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:47 INFO - 'MOZ_NO_REMOTE': '1', 03:46:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:47 INFO - 'NO_EM_RESTART': '1', 03:46:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:47 INFO - 'PWD': '/builds/slave/test', 03:46:47 INFO - 'SHELL': '/bin/bash', 03:46:47 INFO - 'SHLVL': '1', 03:46:47 INFO - 'TERM': 'linux', 03:46:47 INFO - 'TMOUT': '86400', 03:46:47 INFO - 'USER': 'cltbld', 03:46:47 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', 03:46:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:47 INFO - '_': '/tools/buildbot/bin/python'} 03:46:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:46:47 INFO - Running setup.py (path:/tmp/pip-Zh3Kjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:46:47 INFO - Running setup.py (path:/tmp/pip-0hYyMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:46:47 INFO - Running setup.py (path:/tmp/pip-w7rEEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:46:47 INFO - Running setup.py (path:/tmp/pip-bVdZdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:46:47 INFO - Running setup.py (path:/tmp/pip-lIvMVa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:46:47 INFO - Running setup.py (path:/tmp/pip-qKsimt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:46:47 INFO - Running setup.py (path:/tmp/pip-ImMZw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:46:47 INFO - Running setup.py (path:/tmp/pip-AntDmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:46:47 INFO - Running setup.py (path:/tmp/pip-Qc5Fjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:46:47 INFO - Running setup.py (path:/tmp/pip-kfz8hB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:46:47 INFO - Running setup.py (path:/tmp/pip-GpzlAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:46:48 INFO - Running setup.py (path:/tmp/pip-ucdUMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:46:48 INFO - Running setup.py (path:/tmp/pip-ECBRNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:46:48 INFO - Running setup.py (path:/tmp/pip-gS2vHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:48 INFO - Running setup.py (path:/tmp/pip-xRMC07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:46:48 INFO - Running setup.py (path:/tmp/pip-YiOskC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:46:48 INFO - Running setup.py (path:/tmp/pip-DK2xEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:46:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:46:48 INFO - Running setup.py install for manifestparser 03:46:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:46:48 INFO - Running setup.py install for mozcrash 03:46:48 INFO - Running setup.py install for mozdebug 03:46:48 INFO - Running setup.py install for mozdevice 03:46:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:46:48 INFO - Running setup.py install for mozfile 03:46:48 INFO - Running setup.py install for mozhttpd 03:46:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:46:49 INFO - Running setup.py install for mozinfo 03:46:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:46:49 INFO - Running setup.py install for mozInstall 03:46:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:46:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:46:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:46:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:46:49 INFO - Running setup.py install for mozleak 03:46:49 INFO - Running setup.py install for mozlog 03:46:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:46:49 INFO - Running setup.py install for moznetwork 03:46:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:46:49 INFO - Running setup.py install for mozprocess 03:46:49 INFO - Running setup.py install for mozprofile 03:46:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:46:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:46:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:46:49 INFO - Running setup.py install for mozrunner 03:46:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:46:49 INFO - Running setup.py install for mozscreenshot 03:46:50 INFO - Running setup.py install for moztest 03:46:50 INFO - Running setup.py install for mozversion 03:46:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:46:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:46:50 INFO - Cleaning up... 03:46:50 INFO - Return code: 0 03:46:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:50 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 0x15471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b49c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b1d60>, '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 0x18b2a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18b2ef0>, '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': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', '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 03:46:50 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 03:46:50 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 03:46:50 INFO - Using env: {'DISPLAY': ':0', 03:46:50 INFO - 'HOME': '/home/cltbld', 03:46:50 INFO - 'LANG': 'en_US.UTF-8', 03:46:50 INFO - 'LANGUAGE': 'en_US:en', 03:46:50 INFO - 'LOGNAME': 'cltbld', 03:46:50 INFO - 'MAIL': '/var/mail/cltbld', 03:46:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:50 INFO - 'MOZ_NO_REMOTE': '1', 03:46:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:50 INFO - 'NO_EM_RESTART': '1', 03:46:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:50 INFO - 'PWD': '/builds/slave/test', 03:46:50 INFO - 'SHELL': '/bin/bash', 03:46:50 INFO - 'SHLVL': '1', 03:46:50 INFO - 'TERM': 'linux', 03:46:50 INFO - 'TMOUT': '86400', 03:46:50 INFO - 'USER': 'cltbld', 03:46:50 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', 03:46:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:50 INFO - '_': '/tools/buildbot/bin/python'} 03:46:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:46:50 INFO - Running setup.py (path:/tmp/pip-Styv0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:46:50 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)) 03:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:46:50 INFO - Running setup.py (path:/tmp/pip-wjnBsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:46:50 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)) 03:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:46:50 INFO - Running setup.py (path:/tmp/pip-iUNfKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:46:50 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)) 03:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:46:50 INFO - Running setup.py (path:/tmp/pip-U1Uhsn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:46:50 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)) 03:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:46:50 INFO - Running setup.py (path:/tmp/pip-rl0UBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:46:50 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)) 03:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:46:50 INFO - Running setup.py (path:/tmp/pip-XiwpCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:46:50 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)) 03:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:46:50 INFO - Running setup.py (path:/tmp/pip-mWuQnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:46:50 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)) 03:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:46:50 INFO - Running setup.py (path:/tmp/pip-Ng5D0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:46:51 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)) 03:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:46:51 INFO - Running setup.py (path:/tmp/pip-A8Mnql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:46:51 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)) 03:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:46:51 INFO - Running setup.py (path:/tmp/pip-oPwJcz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:46:51 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)) 03:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:46:51 INFO - Running setup.py (path:/tmp/pip-JNBGhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:46:51 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)) 03:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:46:51 INFO - Running setup.py (path:/tmp/pip-taOXfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:46:51 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)) 03:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:46:51 INFO - Running setup.py (path:/tmp/pip-g_aM4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:46:51 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)) 03:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:46:51 INFO - Running setup.py (path:/tmp/pip-xGXJVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:46:51 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)) 03:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:51 INFO - Running setup.py (path:/tmp/pip-VS6c6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:51 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)) 03:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:46:51 INFO - Running setup.py (path:/tmp/pip-3Yd5E7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:46:51 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)) 03:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:46:51 INFO - Running setup.py (path:/tmp/pip-NjCW2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:46:51 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)) 03:46:51 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)) 03:46:51 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)) 03:46:51 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)) 03:46:51 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)) 03:46:51 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)) 03:46:51 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)) 03:46:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:52 INFO - Downloading blessings-1.6.tar.gz 03:46:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:46:52 INFO - Installing collected packages: blessings 03:46:52 INFO - Running setup.py install for blessings 03:46:52 INFO - Successfully installed blessings 03:46:52 INFO - Cleaning up... 03:46:53 INFO - Return code: 0 03:46:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:46:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:46:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:46:53 INFO - Reading from file tmpfile_stdout 03:46:53 INFO - Current package versions: 03:46:53 INFO - argparse == 1.2.1 03:46:53 INFO - blessings == 1.6 03:46:53 INFO - blobuploader == 1.2.4 03:46:53 INFO - docopt == 0.6.1 03:46:53 INFO - functools32 == 3.2.3-2 03:46:53 INFO - jsonschema == 2.5.1 03:46:53 INFO - manifestparser == 1.1 03:46:53 INFO - mozInstall == 1.13 03:46:53 INFO - mozcrash == 1.0 03:46:53 INFO - mozdebug == 0.1 03:46:53 INFO - mozdevice == 0.51 03:46:53 INFO - mozfile == 1.2 03:46:53 INFO - mozhttpd == 0.7 03:46:53 INFO - mozinfo == 0.10 03:46:53 INFO - mozleak == 0.1 03:46:53 INFO - mozlog == 3.5 03:46:53 INFO - moznetwork == 0.27 03:46:53 INFO - mozprocess == 0.25 03:46:53 INFO - mozprofile == 0.28 03:46:53 INFO - mozrunner == 6.13 03:46:53 INFO - mozscreenshot == 0.1 03:46:53 INFO - mozsystemmonitor == 0.3 03:46:53 INFO - moztest == 0.8 03:46:53 INFO - mozversion == 1.4 03:46:53 INFO - psutil == 3.1.1 03:46:53 INFO - requests == 1.2.3 03:46:53 INFO - wsgiref == 0.1.2 03:46:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:46:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:53 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 0x15471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b49c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b1d60>, '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 0x18b2a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18b2ef0>, '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': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', '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 03:46:53 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 03:46:53 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 03:46:53 INFO - Using env: {'DISPLAY': ':0', 03:46:53 INFO - 'HOME': '/home/cltbld', 03:46:53 INFO - 'LANG': 'en_US.UTF-8', 03:46:53 INFO - 'LANGUAGE': 'en_US:en', 03:46:53 INFO - 'LOGNAME': 'cltbld', 03:46:53 INFO - 'MAIL': '/var/mail/cltbld', 03:46:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:53 INFO - 'MOZ_NO_REMOTE': '1', 03:46:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:53 INFO - 'NO_EM_RESTART': '1', 03:46:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:53 INFO - 'PWD': '/builds/slave/test', 03:46:53 INFO - 'SHELL': '/bin/bash', 03:46:53 INFO - 'SHLVL': '1', 03:46:53 INFO - 'TERM': 'linux', 03:46:53 INFO - 'TMOUT': '86400', 03:46:53 INFO - 'USER': 'cltbld', 03:46:53 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', 03:46:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:53 INFO - '_': '/tools/buildbot/bin/python'} 03:46:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:53 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)) 03:46:53 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)) 03:46:53 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)) 03:46:53 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)) 03:46:53 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)) 03:46:53 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)) 03:46:53 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)) 03:46:53 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)) 03:46:53 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)) 03:46:53 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:46:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:46:54 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)) 03:46:54 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)) 03:46:54 INFO - Installing collected packages: simplejson 03:46:54 INFO - Running setup.py install for simplejson 03:46:54 INFO - building 'simplejson._speedups' extension 03:46:54 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 03:46:55 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 03:46:55 INFO - Successfully installed simplejson 03:46:55 INFO - Cleaning up... 03:46:55 INFO - Return code: 0 03:46:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:46: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')]} 03:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46: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')]} 03:46:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:55 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 0x15471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b49c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b1d60>, '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 0x18b2a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18b2ef0>, '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': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', '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 03:46:55 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 03:46:55 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 03:46:55 INFO - Using env: {'DISPLAY': ':0', 03:46:55 INFO - 'HOME': '/home/cltbld', 03:46:55 INFO - 'LANG': 'en_US.UTF-8', 03:46:55 INFO - 'LANGUAGE': 'en_US:en', 03:46:55 INFO - 'LOGNAME': 'cltbld', 03:46:55 INFO - 'MAIL': '/var/mail/cltbld', 03:46:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:55 INFO - 'MOZ_NO_REMOTE': '1', 03:46:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:55 INFO - 'NO_EM_RESTART': '1', 03:46:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:55 INFO - 'PWD': '/builds/slave/test', 03:46:55 INFO - 'SHELL': '/bin/bash', 03:46:55 INFO - 'SHLVL': '1', 03:46:55 INFO - 'TERM': 'linux', 03:46:55 INFO - 'TMOUT': '86400', 03:46:55 INFO - 'USER': 'cltbld', 03:46:55 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', 03:46:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:55 INFO - '_': '/tools/buildbot/bin/python'} 03:46:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:46:56 INFO - Cleaning up... 03:46:56 INFO - Return code: 0 03:46:56 INFO - Running post-action listener: _resource_record_post_action 03:46:56 INFO - Running post-action listener: _start_resource_monitoring 03:46:56 INFO - Starting resource monitoring. 03:46:56 INFO - [mozharness: 2017-09-28 10:46:56.077905Z] Finished create-virtualenv step (success) 03:46:56 INFO - [mozharness: 2017-09-28 10:46:56.078414Z] Running install step. 03:46:56 INFO - Running pre-action listener: _resource_record_pre_action 03:46:56 INFO - Running main action method: install 03:46:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:46:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:46:56 INFO - Reading from file tmpfile_stdout 03:46:56 INFO - Detecting whether we're running mozinstall >=1.0... 03:46:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:46:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:46:56 INFO - Reading from file tmpfile_stdout 03:46:56 INFO - Output received: 03:46:56 INFO - Usage: mozinstall [options] installer 03:46:56 INFO - Options: 03:46:56 INFO - -h, --help show this help message and exit 03:46:56 INFO - -d DEST, --destination=DEST 03:46:56 INFO - Directory to install application into. [default: 03:46:56 INFO - "/builds/slave/test"] 03:46:56 INFO - --app=APP Application being installed. [default: firefox] 03:46:56 INFO - mkdir: /builds/slave/test/build/application 03:46:56 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'] 03:46:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:47:14 INFO - Reading from file tmpfile_stdout 03:47:14 INFO - Output received: 03:47:14 INFO - /builds/slave/test/build/application/firefox/firefox 03:47:14 INFO - Running post-action listener: _resource_record_post_action 03:47:14 INFO - [mozharness: 2017-09-28 10:47:14.263466Z] Finished install step (success) 03:47:14 INFO - [mozharness: 2017-09-28 10:47:14.263718Z] Running setup-mitmproxy step. 03:47:14 INFO - Running pre-action listener: _resource_record_pre_action 03:47:14 INFO - Running main action method: setup_mitmproxy 03:47:14 INFO - Skipping: mitmproxy is not required 03:47:14 INFO - Running post-action listener: _resource_record_post_action 03:47:14 INFO - [mozharness: 2017-09-28 10:47:14.264966Z] Finished setup-mitmproxy step (success) 03:47:14 INFO - [mozharness: 2017-09-28 10:47:14.265204Z] Running run-tests step. 03:47:14 INFO - Running pre-action listener: _resource_record_pre_action 03:47:14 INFO - Running pre-action listener: _set_gcov_prefix 03:47:14 INFO - Running main action method: run_tests 03:47:14 WARNING - Try message not found. 03:47:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:47:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:47:14 INFO - Python 2.7.3 03:47:14 INFO - Return code: 0 03:47:14 INFO - grabbing minidump binary from tooltool 03:47:14 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')]} 03:47:14 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 0x18b1d60>, '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 0x18b2a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18b2ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:47:14 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 03:47:14 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 03:47:14 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 03:47:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:47:14 INFO - Return code: 0 03:47:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:47:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:47:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:47:14 INFO - ENV: RUST_BACKTRACE is now full 03:47:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:47:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:47:14 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/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 03:47:14 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/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-024 --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 03:47:14 INFO - Using env: {'DISPLAY': ':0', 03:47:14 INFO - 'HOME': '/home/cltbld', 03:47:14 INFO - 'LANG': 'en_US.UTF-8', 03:47:14 INFO - 'LANGUAGE': 'en_US:en', 03:47:14 INFO - 'LOGNAME': 'cltbld', 03:47:14 INFO - 'MAIL': '/var/mail/cltbld', 03:47:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:47:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:47:14 INFO - 'MOZ_ACCELERATED': '1', 03:47:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:14 INFO - 'MOZ_NO_REMOTE': '1', 03:47:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:47:14 INFO - 'MOZ_WEBRENDER': '1', 03:47:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:14 INFO - 'NO_EM_RESTART': '1', 03:47:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:14 INFO - 'PWD': '/builds/slave/test', 03:47:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:47:14 INFO - 'RUST_BACKTRACE': 'full', 03:47:14 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:47:14 INFO - 'SHELL': '/bin/bash', 03:47:14 INFO - 'SHLVL': '1', 03:47:14 INFO - 'TERM': 'linux', 03:47:14 INFO - 'TMOUT': '86400', 03:47:14 INFO - 'USER': 'cltbld', 03:47:14 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', 03:47:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:14 INFO - '_': '/tools/buildbot/bin/python'} 03:47:14 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/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 03:47:14 INFO - mozversion application_buildid: 20170928094443 03:47:14 INFO - mozversion application_changeset: 76a26ef7c493311c170ae83eb0c1d6592a21396d 03:47:14 INFO - mozversion application_display_name: Nightly 03:47:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:47:14 INFO - mozversion application_name: Firefox 03:47:14 INFO - mozversion application_remotingname: firefox 03:47:14 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:47:14 INFO - mozversion application_vendor: Mozilla 03:47:14 INFO - mozversion application_version: 58.0a1 03:47:14 INFO - mozversion platform_buildid: 20170928094443 03:47:14 INFO - mozversion platform_changeset: 76a26ef7c493311c170ae83eb0c1d6592a21396d 03:47:14 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:47:14 INFO - mozversion platform_version: 58.0a1 03:47:14 INFO - using testdate: 1506595634 03:47:14 INFO - actual date: 1506595634 03:47:14 INFO - starting webserver on 'localhost:50263' 03:47:14 INFO - SUITE-START | Running 2 tests 03:47:14 INFO - TEST-START | dromaeo_css 03:47:14 INFO - Initialising browser for dromaeo_css test... 03:47:14 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50263/getInfo.html -profile /tmp/tmpi7OVPL/profile 03:47:14 INFO - TEST-INFO | started process 18373 (/builds/slave/test/build/application/firefox/firefox http://localhost:50263/getInfo.html) 03:47:20 INFO - TEST-INFO | 18373: exit 0 03:47:20 INFO - Browser initialized. 03:47:20 INFO - Running cycle 1/1 for dromaeo_css test... 03:47:20 INFO - Using env: {'DISPLAY': ':0', 03:47:20 INFO - 'HOME': '/home/cltbld', 03:47:20 INFO - 'JSGC_DISABLE_POISONING': '1', 03:47:20 INFO - 'LANG': 'en_US.UTF-8', 03:47:20 INFO - 'LANGUAGE': 'en_US:en', 03:47:20 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:47:20 INFO - 'LOGNAME': 'cltbld', 03:47:20 INFO - 'MAIL': '/var/mail/cltbld', 03:47:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:47:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:47:20 INFO - 'MOZ_ACCELERATED': '1', 03:47:20 INFO - 'MOZ_CRASHREPORTER': '1', 03:47:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:20 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:47:20 INFO - 'MOZ_NO_REMOTE': '1', 03:47:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:47:20 INFO - 'MOZ_WEBRENDER': '1', 03:47:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:20 INFO - 'NO_EM_RESTART': '1', 03:47:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:20 INFO - 'PWD': '/builds/slave/test', 03:47:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:47:20 INFO - 'RUST_BACKTRACE': 'full', 03:47:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:47:20 INFO - 'SHELL': '/bin/bash', 03:47:20 INFO - 'SHLVL': '1', 03:47:20 INFO - 'TERM': 'linux', 03:47:20 INFO - 'TMOUT': '86400', 03:47:20 INFO - 'USER': 'cltbld', 03:47:20 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', 03:47:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:20 INFO - '_': '/tools/buildbot/bin/python'} 03:47:20 INFO - TEST-INFO | started process 18622 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpi7OVPL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:47:21 INFO - PID 18622 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:47:21 INFO - PID 18622 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:47:21 INFO - PID 18622 | 03:47:21 INFO - PID 18622 | (/builds/slave/test/build/application/firefox/firefox:18680): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:47:21 INFO - PID 18622 | 03:47:32 INFO - PID 18622 | RSS: Main: 199540736 03:47:32 INFO - PID 18622 | 03:50:28 INFO - PID 18622 | Cycle 1(1): loaded http://localhost:50263/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50263/tests/dromaeo/cssquery-ext.html) 03:50:30 INFO - PID 18622 | RSS: Main: 195436544 03:50:30 INFO - PID 18622 | 03:53:32 INFO - PID 18622 | Cycle 1(1): loaded http://localhost:50263/tests/dromaeo/cssquery-ext.html (next: http://localhost:50263/tests/dromaeo/cssquery-jquery.html) 03:53:33 INFO - PID 18622 | RSS: Main: 194625536 03:53:33 INFO - PID 18622 | 03:56:35 INFO - PID 18622 | Cycle 1(1): loaded http://localhost:50263/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50263/tests/dromaeo/cssquery-mootools.html) 03:56:36 INFO - PID 18622 | RSS: Main: 195678208 03:56:36 INFO - PID 18622 | 03:59:38 INFO - PID 18622 | Cycle 1(1): loaded http://localhost:50263/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50263/tests/dromaeo/cssquery-prototype.html) 03:59:38 INFO - PID 18622 | RSS: Main: 196034560 03:59:38 INFO - PID 18622 | 04:02:42 INFO - PID 18622 | Cycle 1(1): loaded http://localhost:50263/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50263/tests/dromaeo/cssquery-yui.html) 04:02:43 INFO - PID 18622 | RSS: Main: 199073792 04:02:43 INFO - PID 18622 | 04:05:45 INFO - PID 18622 | Cycle 1(1): loaded http://localhost:50263/tests/dromaeo/cssquery-yui.html (next: http://localhost:50263/tests/dromaeo/cssquery-dojo.html) 04:05:45 INFO - PID 18622 | RSS: Main: 198770688 04:05:45 INFO - PID 18622 | 04:05:45 INFO - PID 18622 | __start_tp_report 04:05:45 INFO - PID 18622 | _x_x_mozilla_page_load 04:05:45 INFO - PID 18622 | _x_x_mozilla_page_load_details 04:05:45 INFO - PID 18622 | |i|pagename|runs| 04:05:45 INFO - PID 18622 | |0;dojo.html;3182.0612919188843;3182.236263296808;3189.776715629907;3207.5830142081527;3253.6095668519765;6120.14317995481;6147.723352449139;6194.403930489669;6281;6338.492920566361;6152.5693201475915;6176.258848938133;6191.969040154793;6212.223472065992;6240.719167637456;5696.060150075243;5739.684317362543;5771.105478650816;5789.739461724233;5845.503132233765;5741.626794258381;5804.825855224329;5844.707764611774;5850.122481627747;5853.9170253503025;77.8064072480504;5683.374828768832;5773.076307790754;5857.502112320437;5865.736041878125;5269.499397557226;5627.971860140714;5723.227364305807;5745.712714364265;5837.64974101551;5680.147977803321;5685.971570142165;5716.371199168088;5751.654900705971;5801.912971305392;6106.6336019838955;6170.598911070766;6219.259097821082;6223.751049957997;6290.276618188921;6656.800295991128;6662;6705.396514313687;6725.05849181115;6750.696218670122;6535.117759102508;6706.329367063304;6720.227173875018;6795.422389096932;6805;6174;6180.258368995749;6184.443400094013;6191.876162476725;6195.7521699131985;6750.189977202767;6803.115594972622;6817.863642727168;6825.658717064127;6829.453643708492;6481.254655714653;6482.805515834532;6543.247526534415;6551.246606640298;6564.409203171737;6609.372109649576;6694.828405029198;6702.296258892844;6764.661766911635;6790.5246632736225;6616.900746488806;6645.933540664628;6669.333066693389;6735.797926062226;6737.225219099768;6418.454431373386;6694.364035416627;6702.631355275506;6768.83077923046;6780.966095169591;5205.9219111713355;5212.9739351303;5308.654937429054;5311.760970756325;5343.625946183807;4599.149157405824;4629;4665.743384113906;4670.7664616769025;4671.649626278043;5656.575756818255;5705.5720820938595;5744.281964754406;5776.451237132465;5777.62445441045;6439.452646525004;6629.668516574152;6660.833479162963;6711.798646040626;6725.730970761213;5615.129654903497;5663.745131469093;5668.943310566924;5671.716414179275;5705.115707065412;5772.047612144033;5783.566232532577;5789.334226563915;5802.216700745345;5819.88360232793;6464.709088091047;6488.448481878999;6533.640649764308;6534.673266336664;6620.834479137964;6619.966900165468;6624.205095388585;6673.599584024974;6716.664166791641;6759.121314229119;6226.40849119333;6400.967995159995;6410.647414392253;6419.2938776735455;6428.550001500037;6432.6462044588;6460.612363258031;6489.935100648933;6521.315261897622;6532.085508028975;6599.53803233769;6625.801225963229;6630.038644396439;6645.0696902434665;6647.634380109141;6480;6528.053432252392;6535.183102112236;6593.142891424084;6599.373059559334;6407.583507072025;6461.06314584392;6564.5076619254705;6569.507286953402;6584.670766461562;6217.813465596039;6342.460890824332;6348.888944434613;6387.712552935222;6401.42387185137;6442.355764423708;6470.288268290578;6491.902621460587;6503.382178693206;6543.345665433609;6188.226471691039;6246.968765156145;6273.309935906956;6283.214598175228;6306.117143599991 04:05:45 INFO - PID 18622 | |1;ext.html;878.4509681449094;899.3030401438884;902.021306882033;902.5171533229724;903.8779764731768;39735;40598;43516.564834351644;44011.11977760451;44073.11853762923;39315.41026884599;39475.80262098693;39781;39789.40315895264;39884;7244.203137654854;7425.368843648296;7434.145073316584;7908.367330613537;8086.474379165365;7957.482763620375;8038.799030024237;8059.07320658123;8064.27421532062;8066.838663226732;8048.637811298506;8118.715844945428;8133.471324363927;8140.308073813703;8145.389095817808;8063.354931605458;8090.352771778244;8102.635381407792;8104.270615644561;8106.027276726831;7947.72182973599;7997.840043199161;8004.959975200145;8015.879761803578;8016.67933282668;38471.61528384708;38627.420588691195;38899;39005.21989560193;39098.804505977576;3534.0634731796094;3566.0193446802077;3570.6786389225094;3572.7856328620364;3573.0352804742834;32309.35381292361;32368.676313237036;32745.83627081873;32772;32803.50794738081;5222.477752224785;5256.448072952323;5296.020236256305;5318.734063296858;5354.116570765819;185017;185074.14925850704;185428;185739;185792;43450;49768.75115624471;49799.75100124476;50070;50370.24444633334;46218;51223;52211;52531;52592.73703631534;15185.240737963057;15203.923980380026;15256.618584535254;15340.232988350537;15377;12256.44845981933;12320.75358492843;12391.75216495665;12497.062720296015;12536.561220357244;14154.929225353808;14305.499307524187;14369.56891293263;14457.566273011826;14511.927440362731;5687.203791469197;5734.856628584319;5813.912791308134;5880;5968.403159684084;993.9204863611038;1005.7133716890651;1018.2770233134557;1030.160419258302;1041.6770801051546;23566.821658916986;23645;23664.7633523666;23672.408189795395;23689.763102368757;1078.9568417263379;1120.5797825815318;1129.1136457880689;1132.9546818127346;1136.0627482326947;12086.214396064228;12526.436310366056;12616;12617.558385456465;12793.680157996125;225681;227569;228041;228831;230461;54732;54772;54924.17613735797;55004.72497637486;55364.72317638386;1418.2625034981752;1466.5673626280272;1467.501049643105;1483.666175110613;1488.092263719152;22202.777972220716;23100.422489437897;23130.768692313533;23135.65296520587;23208.18771342995;38444.807775960944;38550.42174367331;38564.6143538561;38601.227975439775;38821.8058909715;194310;194764;194884;196233;196358;29886.25284367908;31722.682773172895;32176.35647287088;32267.35465290728;35150;43820.56179438292;43980.34029489496;44450.333245001995;44645.55354446544;44860.551394486945;32426.513602296458;32606.34787304288;32983.34033319272;33117.66882331242;33600.663993359754;31991.520127198575;32086.588703657628;32495.837520812245;32508.18729531686;33266.833665832484;36630.63369366272;36653.63346366502;36811.07972300714;37010;37059.8147009274;31105.8444707775;31479.84260078685;31515.684843151274;31558;31624.36751265008;24087.638685420083;24213;24519.632205516577;24565.631515526922;25011.12461063854 04:05:45 INFO - PID 18622 | |2;jquery.html;573.8421933968161;584.2900875436344;591.1694069831888;596.8507873031742;610.8198081565939;22018.229361972335;22820.65769013466;23098.88450557749;23166.420839479022;23186.42033949152;24601;24709.258722238315;26072.739272607316;26254.868725656346;26340.868295658496;6494.285628580864;6621.536492445531;6651.634160121192;6686.598804071771;6689.531732778683;6453.9677301613665;6610.834729131786;6702.731890724365;6704.1954965403975;6714.731410743564;6610.636414997173;6680.265170831205;6680.7327706891665;6680.76617318394;6694.631795251284;6565;6606.075149479053;6637.269900310937;6678.833029174261;6686.966565167143;6203.906941395882;6223.533235007392;6235.844103897393;6237.656928868911;6256.343083976162;29484;29867.40265194684;30565.388692226254;30581.847090764626;30748.38503229945;2069.244725675118;2069.3584988653415;2111.7043613894066;2111.862728922615;2117.2589593642256;19411.514712132168;20015.399538013884;20015.89992050045;20031.599368012558;20088.799112008837;2395.4490467192645;2428.8542687438635;2488.6764720586207;2489.21589699246;2491.862947537885;115838;121321.39339303247;138269;138699.6130038697;146054.26972865174;14415.625843496226;14902.850971490363;15010.849891500946;15027.09837409759;15593.532194034196;24622.384440388774;24645.38386540315;24686.506269874502;24716.38209044788;24865.87567062153;9744.366616169926;9999.55002024896;10021.148202402732;10029.849552256723;10169.847452288222;9001.729948101567;9046.86429703555;9052.958909725334;9178.173964343241;9220.44677319363;9034.774130646787;9139.680111196076;9153.496557689392;9187.356885018118;9368.484733339732;2665.3869609989424;2683.1011611110107;2744.108164846433;2803.327201471632;2836.8156069855395;949.5679465843162;969.418348990614;975.2588033094898;979.9216062714966;989.7525618595351;20181.697274540893;20245.595088098155;20385.286514971904;20415.387538373874;20527.58944821095;1030.685640879524;1031.2729525684374;1032.3754128752148;1042.155853758458;1054.6097943760858;11154;11288.661340159644;11372.14708896836;11388.3167009978;11404.600838970597;469319;481191;504017;506638;515029;111058;112777;117820;117827.41086294514;118154.40922795502;1170.0931777872217;1180.2741314091943;1187.2342339191173;1194.0387987669835;1197.958071467512;18217.089145542406;18353.3576324828;18521.907390462962;18731.90634046821;18826.435206943814;51759.48240517547;52788.20817687814;53696.92606147933;54018;55201.72399137979;66654.66672666605;66906.33093669;68344.3165568338;68514.31485685278;69724.6513767428;14754.262286885827;14986.625334366729;15117.924410377878;15136.470223542123;15272.694546109238;23555;23817.880910596028;24170.154044608353;24256.1510347137;24341.756582433947;18616.06919654039;18670.90664546723;18685.626287474446;18839.71740423922;19190.656654034643;16301.184940752677;16327.265273062978;16786.58033549122;17188.31246750166;17198.22607982619;18812.341568045053;18870.433886983414;18888;18908;18946.810531894505;14653.900957428023;14927.776083358542;14968.176750278404;15010;15047.47333843354;18266.908665456587;18435.72346414778;18448.35430759917;18514.351997680016;18567 04:05:45 INFO - PID 18622 | |3;mootools.html;1340.3968214303563;1380.9240491772948;1382.4262930883683;1387.6947071644236;1390.652336915771;14921.626959326022;15555;15574.688506229897;15577.454789082385;15678.76481852773;15262;15318.310676019579;15349;15358;15372.23138843059;2609.621604867293;2719.075514325129;2741.300968253093;2755.9035433759823;2756.4073724149303;2659.5478768609382;2723.360010397555;2725.318670332417;2737.507248695234;2738.6850512191054;2654.7345265473396;2738.4523095381005;2743.9588406173825;2768.418632087254;2773.126465163477;2710.268227578553;2723.4416944526324;2763.8618069096665;2764.8064635475525;2781.6105745195687;2450.534398464286;2673.42521357909;2687.6371689821817;2688.8790004449847;2702.4189799193073;15163.09021458716;15167.848321516754;15262.236888155549;15272.236388180658;15292.311845966959;7950.165232650604;8099.635516401776;8187.345012398994;8226.05400378958;8228.958855205745;5070.467600901926;5332.840014799563;5364.302640656729;5386.165144402624;5428.5928555358505;1989.383291179739;1992.3126521350111;2003.5892642008498;2044.6217449771839;2046.5190680190144;25904.87047564769;26219.21342359732;26353.868230658914;26385.07652232182;26461.280016798846;6583.374579415044;6810.965945170342;6816.182058153054;6917.13535808024;6973.232944376115;17314.220860061327;17336.479905602853;17364.305427783;17380.739288910678;17386.913065434594;7677.961610191914;7989.560574168477;8017.358611311081;8028.598570071473;8034.558099304478;7207.171175314801;7315.743948961761;7336.596487193256;7468.10382754073;7478.551286922802;4929.901401971941;5117.488251174853;5122.769475373617;5139.331886854685;5159.561437277874;1471.257015207335;1496.5959191018364;1504.811898512686;1509.5546813689987;1531.0124969394678;729.4711334282603;825.3314814999869;868.847951608466;879.6965047058754;895.3329769321766;7723.7682869514;7876.645550950221;7945.284924356835;7962.124166341698;8074.434789564674;965.5172413793103;986.541258314887;1007.6825799873017;1017;1020.9744756381001;4712.316714076508;4810;4883.536064073907;4968.279599458058;5175.818846340435;21077.683834742493;21472;21742.347729568137;21849.781502184775;22060.779392206194;21647.567048658937;21673.45816354572;22317.442063948205;22475.325740227818;22754.43113922165;10687;11136;11197.272177308449;11230.663080107774;11279.548818047351;7377.446691498267;7744.92255077442;7765.961170194113;7793.337566306927;7833;18688.159032843283;19035.428937131906;19197.90401047986;19357.90321048442;19560.119794608992;18706.532336691693;19664.311749088713;19946;19959.501012474804;20344.491387715425;10996.945015274872;11087;11111.333320000826;11171.832422513831;11173.329600224013;14034.368453419416;14187.716245674823;14214.502492412716;14238;14240.715185696436;11260.211785174959;11582.131340149692;11706.531738730355;11975.94012029969;12095.092868034757;11390.943045284721;11499.94250028778;11534.077273818242;11640;11674.649760507198;10404.73988150272;10512.053915147673;10521.526531305957;10533.525991330562;10541.367517948947;7005.159380874327;7067.469939754436;7079.610621415872;7158.749443769443;7167.498275120692;6933.445324373937;7035.753748618774;7051.294870513113;7106.751263705745;7140.285971402818 04:05:45 INFO - PID 18622 | |4;prototype.html;512.6898226572926;525.3721802446078;544.186441270301;553.2724467325467;561.6152935239361;6147.784827531037;6177.969110154443;6194.194754681891;6218.689065546727;6239.4696450801675;6083.878322433548;6091.512678985681;6110.464157374684;6183.567150299481;6184.505239580834;5110.795568177268;5111.974440127794;5114.3095682083;5117.641765076446;5127.384713834345;5009.874753131165;5082.2122571001555;5122.795088196468;5128.8974220515565;5180.740962951856;4912.238603016538;4958.7024778513405;4980.77586508608;5004.549590536851;5028.957480724164;5006.6245031622775;5021.598272138239;5047.4447810740785;5049.64652474327;5075.568576671006;4624.329472226542;4654.488006319302;4671.953280467186;4696.7651617419;4713.9764301178275;5925.140854576105;5982.491488223506;6000.909986350208;6022.277326720821;6052.606580572248;5723.742431590588;5763.077907534775;5781.595288329819;5834.474897259224;5853.473187413153;6330.987042073292;6336.429721325103;6399.520035997319;6423.7430502779835;6426.582272152295;4893.779779909913;4980.626453016038;5027.09512287788;5061;5065.645404821664;6483.643399613019;6616.867662646769;6657.633830139341;6717.630530320879;6733.764318248838;5346.759395827197;5347.331583552056;5356.143017117242;5364.14173732201;5367.946320536823;5369.7583608737705;5390.676559406448;5431.076716958127;5487.64330318528;5488.039593071197;5483.4516548344845;5568.860778480571;5573.303337082864;5751.281089863767;5871.941280587225;6404.519661025442;6433.646149461731;6472.3204063573585;6483.384078512534;6513.534454747739;1710.4868539438119;1717.544850614588;1731.5497970527754;1745.9698777721205;1746.0920321432784;1183.3432444992939;1219.359836086062;1236.863944966053;1241.888230059299;1245.975080498403;5542.91685624716;5543.52880005204;5549.889002219933;5560.888782224333;5599.768051028767;6449.32282110381;6476.384743449365;6515.283318834924;6562.146920900253;6585.308542603054;5437.483439073281;5500.744694675244;5518.475744804237;5527.53015993645;5571.1086226203615;3364.360771453465;3398.4222682144095;3439;3457.619661837196;3473.89578312651;6294.716737746812;6318.210223722035;6375;6391.009393544008;6440.162778838721;6620.2386725527185;6620.775156595949;6630.574426498326;6636.535442518977;6690.13028306317;6272.153259310117;6318.1872677136225;6324.3043265240785;6333.651649159248;6371.362863713592;6358.107061287011;6386.68066596659;6413.807585772434;6444;6457.192850893638;5920.319163296103;6364.395382438846;6403.935960640334;6411.166548348685;6505.934940650533;6097.878042439215;6217.440430361289;6254.155688981929;6269.216347956505;6280.748770049329;4755.334253204624;4909.754512274443;4942.4810394909455;4948.703077815343;5008.749562521786;6575.178102737158;6584.275729669636;6592.2089349276475;6601.405873471411;6611.66941652925;6266.624002559861;6269.592476489013;6292.370762923671;6364.395382438846;6380.266269379081;6289.339619339903;6306;6347.904781428375;6358.427741503287;6400.967995159995;6203.627782333075;6211.720472578835;6250.874982500233;6269.247690277196;6284.748610055546;6350.872982540416;6381.649009304347;6390.360963903572;6469.45009674183;6494.707738151889;5971.253593300838;6146.770645870754;6148.692565371624;6170.79669464468;6192.411720886508 04:05:45 INFO - PID 18622 | |5;yui.html;896.4979611417602;904.3804993579398;914.6615752171698;915.8763566918468;920.1166879795396;18430.815691843076;19102.14040368183;19110.853348799064;19111.904440477814;19160.71258931117;18580.907095464536;18915.52711182221;18939.33712320069;18945.33691320804;18973.810261897375;905.0225756183339;914.3005600715458;924.523870206844;926.1155596405416;927.0590350793949;980.8283550378691;993.8608594796734;994.2890833054365;994.6916455898659;995.8257304971637;980.901909809017;1010.1111022300365;1010.8584798128268;1017.3946501831435;1031.417249254169;994.4381424495137;1005.1355833982769;1005.8994100589956;1007.0231875081196;1012.4431562640518;727.2182403915759;728.5774250934498;730.4777084384638;734.8824188129927;737.7159793479495;19248.903755481133;20239.79760202394;20786.896065519726;20975.685364719542;20979.475513112142;21579.784202157934;21756.238531651474;21935;22292.331230062962;22432.88783556088;17025.404110856183;17222.397216097375;17275.74086388705;17277.913610431868;17351.566210844703;3719.7396182267253;3752.2683076800035;3761.680257178143;3767.2277183177657;3780.281746468162;108983;128715;140732;141001;142749.2862535691;17529.386471473692;17544.736828947574;17712.46862594124;17934.282628694706;18022.5494362642;30241;30673.539896901566;30919.2270193247;31063.378732425226;31072.844635776677;8812.779680508038;8987.146221108984;8988.280937524982;9031.954840225757;9036.27709783229;4194.706370554093;4272.33778764292;4289.24938135825;4294.0767734937135;4304.053108316165;5348.689571055116;5424.267723857229;5448.700321482356;5471.316085489314;5509.586780991442;1002.9247806414548;1035.7773078787945;1045.9529321180412;1046.2205656785743;1061.936283822973;666.466826538767;674.6491824251361;692.069166970424;698.7135274537416;701.0290747315006;11632.941835290769;11909.821352679717;12097.153199275966;12373.690657733448;12457.626271211697;669.5614372585964;670.5406796344422;678.9083473730983;685.6640246279287;689.9068625735562;5658.292713410824;5694.373618901917;5749.626274292241;5767.423257674283;5872.794452194238;120604;123595.3820230893;124535;126211;129675.35162324317;99446;101739;101984;102383;103190;83749.16250837536;91588.54205728929;91828.54085729663;92703.53648231715;94626;10543.472826358498;10619.840702389625;10694.358338499715;10742.086922611665;10814.45927703596;43095.35356969585;44251.33622995593;44690.55309446864;44725.32912006388;44809;53713.19430208472;54470.72764636151;55422.44577554173;55514.16728748991;55647.443525565846;14572.198529081003;14603.926980365455;14704.411823527367;14882.404703811726;14914.477993270184;17139.31442742326;17178.226979786188;17219;17322.82677173212;17601.383951561635;15475.148866811984;15501;15591.06453612787;15608;15771.605709857346;15280.312385942438;15310.617234569225;15372.692546149237;15507.379704811681;15630.843691562939;14863.925680371529;15075.623109422353;15335.846641533888;15542.5337239883;15973.760393594337;10713.608747269021;10802.405867677353;10941.45292735344;11241.381724005258;11462;12356.505739770308;12538.93730531378;12587.937060315006;12591.24452532851;12703.42834572428 04:05:45 INFO - PID 18622 | __end_tp_report 04:05:45 INFO - PID 18622 | __start_cc_report 04:05:45 INFO - PID 18622 | _x_x_mozilla_cycle_collect,284 04:05:45 INFO - PID 18622 | __end_cc_report 04:05:45 INFO - PID 18622 | __startTimestamp1506596745251__endTimestamp 04:05:45 INFO - PID 18622 | 04:05:45 INFO - PID 18622 | ------- Summary: start ------- 04:05:45 INFO - PID 18622 | Number of tests: 6 04:05:45 INFO - PID 18622 | 04:05:45 INFO - PID 18622 | [#0] dojo.html Cycles:165 Average:6091.53 Median:6348.89 stddev:855.76 (13.5%) stddev-sans-first:827.39 04:05:45 INFO - PID 18622 | Values: 3182.1 3182.2 3189.8 3207.6 3253.6 6120.1 6147.7 6194.4 6281.0 6338.5 6152.6 6176.3 6192.0 6212.2 6240.7 5696.1 5739.7 5771.1 5789.7 5845.5 5741.6 5804.8 5844.7 5850.1 5853.9 77.8 5683.4 5773.1 5857.5 5865.7 5269.5 5628.0 5723.2 5745.7 5837.6 5680.1 5686.0 5716.4 5751.7 5801.9 6106.6 6170.6 6219.3 6223.8 6290.3 6656.8 6662.0 6705.4 6725.1 6750.7 6535.1 6706.3 6720.2 6795.4 6805.0 6174.0 6180.3 6184.4 6191.9 6195.8 6750.2 6803.1 6817.9 6825.7 6829.5 6481.3 6482.8 6543.2 6551.2 6564.4 6609.4 6694.8 6702.3 6764.7 6790.5 6616.9 6645.9 6669.3 6735.8 6737.2 6418.5 6694.4 6702.6 6768.8 6781.0 5205.9 5213.0 5308.7 5311.8 5343.6 4599.1 4629.0 4665.7 4670.8 4671.6 5656.6 5705.6 5744.3 5776.5 5777.6 6439.5 6629.7 6660.8 6711.8 6725.7 5615.1 5663.7 5668.9 5671.7 5705.1 5772.0 5783.6 5789.3 5802.2 5819.9 6464.7 6488.4 6533.6 6534.7 6620.8 6620.0 6624.2 6673.6 6716.7 6759.1 6226.4 6401.0 6410.6 6419.3 6428.6 6432.6 6460.6 6489.9 6521.3 6532.1 6599.5 6625.8 6630.0 6645.1 6647.6 6480.0 6528.1 6535.2 6593.1 6599.4 6407.6 6461.1 6564.5 6569.5 6584.7 6217.8 6342.5 6348.9 6387.7 6401.4 6442.4 6470.3 6491.9 6503.4 6543.3 6188.2 6247.0 6273.3 6283.2 6306.1 04:05:45 INFO - PID 18622 | 04:05:45 INFO - PID 18622 | [#1] ext.html Cycles:180 Average:37513.09 Median:3568.35 stddev:52763.04 (1478.6%) stddev-sans-first:52839.35 04:05:45 INFO - PID 18622 | Values: 878.5 899.3 902.0 902.5 903.9 39735.0 40598.0 43516.6 44011.1 44073.1 39315.4 39475.8 39781.0 39789.4 39884.0 7244.2 7425.4 7434.1 7908.4 8086.5 7957.5 8038.8 8059.1 8064.3 8066.8 8048.6 8118.7 8133.5 8140.3 8145.4 8063.4 8090.4 8102.6 8104.3 8106.0 7947.7 7997.8 8005.0 8015.9 8016.7 38471.6 38627.4 38899.0 39005.2 39098.8 3534.1 3566.0 3570.7 3572.8 3573.0 32309.4 32368.7 32745.8 32772.0 32803.5 5222.5 5256.4 5296.0 5318.7 5354.1 185017.0 185074.1 185428.0 185739.0 185792.0 43450.0 49768.8 49799.8 50070.0 50370.2 46218.0 51223.0 52211.0 52531.0 52592.7 15185.2 15203.9 15256.6 15340.2 15377.0 12256.4 12320.8 12391.8 12497.1 12536.6 14154.9 14305.5 14369.6 14457.6 14511.9 5687.2 5734.9 5813.9 5880.0 5968.4 993.9 1005.7 1018.3 1030.2 1041.7 23566.8 23645.0 23664.8 23672.4 23689.8 1079.0 1120.6 1129.1 1133.0 1136.1 12086.2 12526.4 12616.0 12617.6 12793.7 225681.0 227569.0 228041.0 228831.0 230461.0 54732.0 54772.0 54924.2 55004.7 55364.7 1418.3 1466.6 1467.5 1483.7 1488.1 22202.8 23100.4 23130.8 23135.7 23208.2 38444.8 38550.4 38564.6 38601.2 38821.8 194310.0 194764.0 194884.0 196233.0 196358.0 29886.3 31722.7 32176.4 32267.4 35150.0 43820.6 43980.3 44450.3 44645.6 44860.6 32426.5 32606.3 32983.3 33117.7 33600.7 31991.5 32086.6 32495.8 32508.2 33266.8 36630.6 36653.6 36811.1 37010.0 37059.8 31105.8 31479.8 31515.7 31558.0 31624.4 24087.6 24213.0 24519.6 24565.6 25011.1 04:05:45 INFO - PID 18622 | 04:05:45 INFO - PID 18622 | [#2] jquery.html Cycles:180 Average:34774.92 Median:13282.80 stddev:83148.03 (626.0%) stddev-sans-first:83341.63 04:05:45 INFO - PID 18622 | Values: 573.8 584.3 591.2 596.9 610.8 22018.2 22820.7 23098.9 23166.4 23186.4 24601.0 24709.3 26072.7 26254.9 26340.9 6494.3 6621.5 6651.6 6686.6 6689.5 6454.0 6610.8 6702.7 6704.2 6714.7 6610.6 6680.3 6680.7 6680.8 6694.6 6565.0 6606.1 6637.3 6678.8 6687.0 6203.9 6223.5 6235.8 6237.7 6256.3 29484.0 29867.4 30565.4 30581.8 30748.4 2069.2 2069.4 2111.7 2111.9 2117.3 19411.5 20015.4 20015.9 20031.6 20088.8 2395.4 2428.9 2488.7 2489.2 2491.9 115838.0 121321.4 138269.0 138699.6 146054.3 14415.6 14902.9 15010.8 15027.1 15593.5 24622.4 24645.4 24686.5 24716.4 24865.9 9744.4 9999.6 10021.1 10029.8 10169.8 9001.7 9046.9 9053.0 9178.2 9220.4 9034.8 9139.7 9153.5 9187.4 9368.5 2665.4 2683.1 2744.1 2803.3 2836.8 949.6 969.4 975.3 979.9 989.8 20181.7 20245.6 20385.3 20415.4 20527.6 1030.7 1031.3 1032.4 1042.2 1054.6 11154.0 11288.7 11372.1 11388.3 11404.6 469319.0 481191.0 504017.0 506638.0 515029.0 111058.0 112777.0 117820.0 117827.4 118154.4 1170.1 1180.3 1187.2 1194.0 1198.0 18217.1 18353.4 18521.9 18731.9 18826.4 51759.5 52788.2 53696.9 54018.0 55201.7 66654.7 66906.3 68344.3 68514.3 69724.7 14754.3 14986.6 15117.9 15136.5 15272.7 23555.0 23817.9 24170.2 24256.2 24341.8 18616.1 18670.9 18685.6 18839.7 19190.7 16301.2 16327.3 16786.6 17188.3 17198.2 18812.3 18870.4 18888.0 18908.0 18946.8 14653.9 14927.8 14968.2 15010.0 15047.5 18266.9 18435.7 18448.4 18514.4 18567.0 04:05:45 INFO - PID 18622 | 04:05:45 INFO - PID 18622 | [#3] mootools.html Cycles:180 Average:9393.74 Median:14414.42 stddev:6777.25 (47.0%) stddev-sans-first:6769.25 04:05:45 INFO - PID 18622 | Values: 1340.4 1380.9 1382.4 1387.7 1390.7 14921.6 15555.0 15574.7 15577.5 15678.8 15262.0 15318.3 15349.0 15358.0 15372.2 2609.6 2719.1 2741.3 2755.9 2756.4 2659.5 2723.4 2725.3 2737.5 2738.7 2654.7 2738.5 2744.0 2768.4 2773.1 2710.3 2723.4 2763.9 2764.8 2781.6 2450.5 2673.4 2687.6 2688.9 2702.4 15163.1 15167.8 15262.2 15272.2 15292.3 7950.2 8099.6 8187.3 8226.1 8229.0 5070.5 5332.8 5364.3 5386.2 5428.6 1989.4 1992.3 2003.6 2044.6 2046.5 25904.9 26219.2 26353.9 26385.1 26461.3 6583.4 6811.0 6816.2 6917.1 6973.2 17314.2 17336.5 17364.3 17380.7 17386.9 7678.0 7989.6 8017.4 8028.6 8034.6 7207.2 7315.7 7336.6 7468.1 7478.6 4929.9 5117.5 5122.8 5139.3 5159.6 1471.3 1496.6 1504.8 1509.6 1531.0 729.5 825.3 868.8 879.7 895.3 7723.8 7876.6 7945.3 7962.1 8074.4 965.5 986.5 1007.7 1017.0 1021.0 4712.3 4810.0 4883.5 4968.3 5175.8 21077.7 21472.0 21742.3 21849.8 22060.8 21647.6 21673.5 22317.4 22475.3 22754.4 10687.0 11136.0 11197.3 11230.7 11279.5 7377.4 7744.9 7766.0 7793.3 7833.0 18688.2 19035.4 19197.9 19357.9 19560.1 18706.5 19664.3 19946.0 19959.5 20344.5 10996.9 11087.0 11111.3 11171.8 11173.3 14034.4 14187.7 14214.5 14238.0 14240.7 11260.2 11582.1 11706.5 11975.9 12095.1 11390.9 11499.9 11534.1 11640.0 11674.6 10404.7 10512.1 10521.5 10533.5 10541.4 7005.2 7067.5 7079.6 7158.7 7167.5 6933.4 7035.8 7051.3 7106.8 7140.3 04:05:45 INFO - PID 18622 | 04:05:45 INFO - PID 18622 | [#4] prototype.html Cycles:180 Average:5436.12 Median:6037.44 stddev:1474.65 (24.4%) stddev-sans-first:1431.74 04:05:45 INFO - PID 18622 | Values: 512.7 525.4 544.2 553.3 561.6 6147.8 6178.0 6194.2 6218.7 6239.5 6083.9 6091.5 6110.5 6183.6 6184.5 5110.8 5112.0 5114.3 5117.6 5127.4 5009.9 5082.2 5122.8 5128.9 5180.7 4912.2 4958.7 4980.8 5004.5 5029.0 5006.6 5021.6 5047.4 5049.6 5075.6 4624.3 4654.5 4672.0 4696.8 4714.0 5925.1 5982.5 6000.9 6022.3 6052.6 5723.7 5763.1 5781.6 5834.5 5853.5 6331.0 6336.4 6399.5 6423.7 6426.6 4893.8 4980.6 5027.1 5061.0 5065.6 6483.6 6616.9 6657.6 6717.6 6733.8 5346.8 5347.3 5356.1 5364.1 5367.9 5369.8 5390.7 5431.1 5487.6 5488.0 5483.5 5568.9 5573.3 5751.3 5871.9 6404.5 6433.6 6472.3 6483.4 6513.5 1710.5 1717.5 1731.5 1746.0 1746.1 1183.3 1219.4 1236.9 1241.9 1246.0 5542.9 5543.5 5549.9 5560.9 5599.8 6449.3 6476.4 6515.3 6562.1 6585.3 5437.5 5500.7 5518.5 5527.5 5571.1 3364.4 3398.4 3439.0 3457.6 3473.9 6294.7 6318.2 6375.0 6391.0 6440.2 6620.2 6620.8 6630.6 6636.5 6690.1 6272.2 6318.2 6324.3 6333.7 6371.4 6358.1 6386.7 6413.8 6444.0 6457.2 5920.3 6364.4 6403.9 6411.2 6505.9 6097.9 6217.4 6254.2 6269.2 6280.7 4755.3 4909.8 4942.5 4948.7 5008.7 6575.2 6584.3 6592.2 6601.4 6611.7 6266.6 6269.6 6292.4 6364.4 6380.3 6289.3 6306.0 6347.9 6358.4 6401.0 6203.6 6211.7 6250.9 6269.2 6284.7 6350.9 6381.6 6390.4 6469.5 6494.7 5971.3 6146.8 6148.7 6170.8 6192.4 04:05:45 INFO - PID 18622 | 04:05:45 INFO - PID 18622 | [#5] yui.html Cycles:180 Average:23785.26 Median:19136.31 stddev:34129.61 (178.4%) stddev-sans-first:34182.08 04:05:45 INFO - PID 18622 | Values: 896.5 904.4 914.7 915.9 920.1 18430.8 19102.1 19110.9 19111.9 19160.7 18580.9 18915.5 18939.3 18945.3 18973.8 905.0 914.3 924.5 926.1 927.1 980.8 993.9 994.3 994.7 995.8 980.9 1010.1 1010.9 1017.4 1031.4 994.4 1005.1 1005.9 1007.0 1012.4 727.2 728.6 730.5 734.9 737.7 19248.9 20239.8 20786.9 20975.7 20979.5 21579.8 21756.2 21935.0 22292.3 22432.9 17025.4 17222.4 17275.7 17277.9 17351.6 3719.7 3752.3 3761.7 3767.2 3780.3 108983.0 128715.0 140732.0 141001.0 142749.3 17529.4 17544.7 17712.5 17934.3 18022.5 30241.0 30673.5 30919.2 31063.4 31072.8 8812.8 8987.1 8988.3 9032.0 9036.3 4194.7 4272.3 4289.2 4294.1 4304.1 5348.7 5424.3 5448.7 5471.3 5509.6 1002.9 1035.8 1046.0 1046.2 1061.9 666.5 674.6 692.1 698.7 701.0 11632.9 11909.8 12097.2 12373.7 12457.6 669.6 670.5 678.9 685.7 689.9 5658.3 5694.4 5749.6 5767.4 5872.8 120604.0 123595.4 124535.0 126211.0 129675.4 99446.0 101739.0 101984.0 102383.0 103190.0 83749.2 91588.5 91828.5 92703.5 94626.0 10543.5 10619.8 10694.4 10742.1 10814.5 43095.4 44251.3 44690.6 44725.3 44809.0 53713.2 54470.7 55422.4 55514.2 55647.4 14572.2 14603.9 14704.4 14882.4 14914.5 17139.3 17178.2 17219.0 17322.8 17601.4 15475.1 15501.0 15591.1 15608.0 15771.6 15280.3 15310.6 15372.7 15507.4 15630.8 14863.9 15075.6 15335.8 15542.5 15973.8 10713.6 10802.4 10941.5 11241.4 11462.0 12356.5 12538.9 12587.9 12591.2 12703.4 04:05:45 INFO - PID 18622 | -------- Summary: end -------- 04:05:45 INFO - PID 18622 | 04:05:46 INFO - TEST-INFO | 18622: exit 0 04:05:46 INFO - TEST-OK | dromaeo_css | took 1111496ms 04:05:46 INFO - TEST-START | kraken 04:05:46 INFO - Initialising browser for kraken test... 04:05:46 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50263/getInfo.html -profile /tmp/tmpVERyWy/profile 04:05:46 INFO - TEST-INFO | started process 18996 (/builds/slave/test/build/application/firefox/firefox http://localhost:50263/getInfo.html) 04:05:52 INFO - TEST-INFO | 18996: exit 0 04:05:52 INFO - Browser initialized. 04:05:52 INFO - Running cycle 1/1 for kraken test... 04:05:52 INFO - Using env: {'DISPLAY': ':0', 04:05:52 INFO - 'HOME': '/home/cltbld', 04:05:52 INFO - 'JSGC_DISABLE_POISONING': '1', 04:05:52 INFO - 'LANG': 'en_US.UTF-8', 04:05:52 INFO - 'LANGUAGE': 'en_US:en', 04:05:52 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:05:52 INFO - 'LOGNAME': 'cltbld', 04:05:52 INFO - 'MAIL': '/var/mail/cltbld', 04:05:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:05:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:05:52 INFO - 'MOZ_ACCELERATED': '1', 04:05:52 INFO - 'MOZ_CRASHREPORTER': '1', 04:05:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:52 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:05:52 INFO - 'MOZ_NO_REMOTE': '1', 04:05:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:05:52 INFO - 'MOZ_WEBRENDER': '1', 04:05:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:52 INFO - 'NO_EM_RESTART': '1', 04:05:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:52 INFO - 'PWD': '/builds/slave/test', 04:05:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:05:52 INFO - 'RUST_BACKTRACE': 'full', 04:05:52 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:05:52 INFO - 'SHELL': '/bin/bash', 04:05:52 INFO - 'SHLVL': '1', 04:05:52 INFO - 'TERM': 'linux', 04:05:52 INFO - 'TMOUT': '86400', 04:05:52 INFO - 'USER': 'cltbld', 04:05:52 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425', 04:05:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:52 INFO - '_': '/tools/buildbot/bin/python'} 04:05:52 INFO - TEST-INFO | started process 19238 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVERyWy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:05:52 INFO - PID 19238 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 04:05:53 INFO - PID 19238 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 04:05:53 INFO - PID 19238 | 04:05:53 INFO - PID 19238 | (/builds/slave/test/build/application/firefox/firefox:19296): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:05:53 INFO - PID 19238 | 04:06:03 INFO - PID 19238 | RSS: Main: 201367552 04:06:03 INFO - PID 19238 | 04:06:06 INFO - PID 19238 | Cycle 1(1): loaded http://localhost:50263/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50263/tests/kraken/driver.html?testName=audio-beat-detection) 04:06:07 INFO - PID 19238 | RSS: Main: 198565888 04:06:07 INFO - PID 19238 | 04:06:10 INFO - PID 19238 | Cycle 1(1): loaded http://localhost:50263/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50263/tests/kraken/driver.html?testName=audio-dft) 04:06:10 INFO - PID 19238 | RSS: Main: 198787072 04:06:10 INFO - PID 19238 | 04:06:13 INFO - PID 19238 | Cycle 1(1): loaded http://localhost:50263/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50263/tests/kraken/driver.html?testName=audio-fft) 04:06:14 INFO - PID 19238 | RSS: Main: 194801664 04:06:14 INFO - PID 19238 | 04:06:16 INFO - PID 19238 | Cycle 1(1): loaded http://localhost:50263/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50263/tests/kraken/driver.html?testName=audio-oscillator) 04:06:17 INFO - PID 19238 | RSS: Main: 194891776 04:06:17 INFO - PID 19238 | 04:06:19 INFO - PID 19238 | Cycle 1(1): loaded http://localhost:50263/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50263/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:06:19 INFO - PID 19238 | RSS: Main: 195198976 04:06:19 INFO - PID 19238 | 04:06:23 INFO - PID 19238 | Cycle 1(1): loaded http://localhost:50263/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50263/tests/kraken/driver.html?testName=imaging-darkroom) 04:06:23 INFO - PID 19238 | RSS: Main: 197640192 04:06:23 INFO - PID 19238 | 04:06:27 INFO - PID 19238 | Cycle 1(1): loaded http://localhost:50263/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50263/tests/kraken/driver.html?testName=imaging-desaturate) 04:06:28 INFO - PID 19238 | RSS: Main: 197070848 04:06:28 INFO - PID 19238 | 04:06:31 INFO - PID 19238 | Cycle 1(1): loaded http://localhost:50263/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50263/tests/kraken/driver.html?testName=json-parse-financial) 04:06:31 INFO - PID 19238 | RSS: Main: 197894144 04:06:31 INFO - PID 19238 | 04:06:33 INFO - PID 19238 | Cycle 1(1): loaded http://localhost:50263/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50263/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:06:33 INFO - PID 19238 | RSS: Main: 197816320 04:06:33 INFO - PID 19238 | 04:06:35 INFO - PID 19238 | Cycle 1(1): loaded http://localhost:50263/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50263/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:06:36 INFO - PID 19238 | RSS: Main: 194822144 04:06:36 INFO - PID 19238 | 04:06:38 INFO - PID 19238 | Cycle 1(1): loaded http://localhost:50263/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50263/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:06:39 INFO - PID 19238 | RSS: Main: 194916352 04:06:39 INFO - PID 19238 | 04:06:41 INFO - PID 19238 | Cycle 1(1): loaded http://localhost:50263/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50263/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:06:42 INFO - PID 19238 | RSS: Main: 195608576 04:06:42 INFO - PID 19238 | 04:06:45 INFO - PID 19238 | Cycle 1(1): loaded http://localhost:50263/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50263/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:06:45 INFO - PID 19238 | RSS: Main: 194998272 04:06:45 INFO - PID 19238 | 04:06:47 INFO - PID 19238 | Cycle 1(1): loaded http://localhost:50263/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50263/tests/kraken/driver.html?testName=ai-astar) 04:06:47 INFO - PID 19238 | RSS: Main: 196255744 04:06:47 INFO - PID 19238 | 04:06:47 INFO - PID 19238 | __start_tp_report 04:06:47 INFO - PID 19238 | _x_x_mozilla_page_load 04:06:47 INFO - PID 19238 | _x_x_mozilla_page_load_details 04:06:47 INFO - PID 19238 | |i|pagename|runs| 04:06:47 INFO - PID 19238 | |0;ai-astar;111;113;115;112;113;107;113;106;108;115 04:06:47 INFO - PID 19238 | |1;audio-beat-detection;129;119;125;117;125;118;125;115;127;120 04:06:47 INFO - PID 19238 | |2;audio-dft;163;169;165;163;163;177;176;165;175;172 04:06:47 INFO - PID 19238 | |3;audio-fft;81;79;83;75;85;80;79;76;79;79 04:06:47 INFO - PID 19238 | |4;audio-oscillator;83;81;87;82;83;81;86;84;85;81 04:06:47 INFO - PID 19238 | |5;imaging-gaussian-blur;115;117;123;118;114;120;114;116;114;115 04:06:47 INFO - PID 19238 | |6;imaging-darkroom;152;148;154;149;147;148;148;148;149;149 04:06:47 INFO - PID 19238 | |7;imaging-desaturate;100;95;105;97;95;97;99;100;96;96 04:06:47 INFO - PID 19238 | |8;json-parse-financial;57;52;52;50;51;54;59;52;53;53 04:06:47 INFO - PID 19238 | |9;json-stringify-tinderbox;41;38;37;40;48;36;46;46;40;37 04:06:47 INFO - PID 19238 | |10;stanford-crypto-aes;72;75;72;71;74;80;80;79;77;79 04:06:47 INFO - PID 19238 | |11;stanford-crypto-ccm;115;117;114;119;111;115;113;117;111;107 04:06:47 INFO - PID 19238 | |12;stanford-crypto-pbkdf2;165;162;165;163;169;162;165;161;162;166 04:06:47 INFO - PID 19238 | |13;stanford-crypto-sha256-iterative;68;65;63;63;68;65;65;64;63;65 04:06:47 INFO - PID 19238 | __end_tp_report 04:06:47 INFO - PID 19238 | __start_cc_report 04:06:47 INFO - PID 19238 | _x_x_mozilla_cycle_collect,772 04:06:47 INFO - PID 19238 | __end_cc_report 04:06:47 INFO - PID 19238 | __startTimestamp1506596807891__endTimestamp 04:06:47 INFO - PID 19238 | 04:06:47 INFO - PID 19238 | ------- Summary: start ------- 04:06:47 INFO - PID 19238 | Number of tests: 14 04:06:47 INFO - PID 19238 | 04:06:47 INFO - PID 19238 | [#0] ai-astar Cycles:10 Average:111.30 Median:113.00 stddev:3.23 (2.9%) stddev-sans-first:3.43 04:06:47 INFO - PID 19238 | Values: 111.0 113.0 115.0 112.0 113.0 107.0 113.0 106.0 108.0 115.0 04:06:47 INFO - PID 19238 | 04:06:47 INFO - PID 19238 | [#1] audio-beat-detection Cycles:10 Average:122.00 Median:125.00 stddev:4.76 (3.8%) stddev-sans-first:4.32 04:06:47 INFO - PID 19238 | Values: 129.0 119.0 125.0 117.0 125.0 118.0 125.0 115.0 127.0 120.0 04:06:47 INFO - PID 19238 | 04:06:47 INFO - PID 19238 | [#2] audio-dft Cycles:10 Average:168.80 Median:170.50 stddev:5.75 (3.4%) stddev-sans-first:5.70 04:06:47 INFO - PID 19238 | Values: 163.0 169.0 165.0 163.0 163.0 177.0 176.0 165.0 175.0 172.0 04:06:47 INFO - PID 19238 | 04:06:47 INFO - PID 19238 | [#3] audio-fft Cycles:10 Average:79.60 Median:79.50 stddev:2.95 (3.7%) stddev-sans-first:3.09 04:06:47 INFO - PID 19238 | Values: 81.0 79.0 83.0 75.0 85.0 80.0 79.0 76.0 79.0 79.0 04:06:47 INFO - PID 19238 | 04:06:47 INFO - PID 19238 | [#4] audio-oscillator Cycles:10 Average:83.30 Median:83.50 stddev:2.16 (2.6%) stddev-sans-first:2.29 04:06:47 INFO - PID 19238 | Values: 83.0 81.0 87.0 82.0 83.0 81.0 86.0 84.0 85.0 81.0 04:06:47 INFO - PID 19238 | 04:06:47 INFO - PID 19238 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:116.50 stddev:2.99 (2.6%) stddev-sans-first:3.11 04:06:47 INFO - PID 19238 | Values: 115.0 117.0 123.0 118.0 114.0 120.0 114.0 116.0 114.0 115.0 04:06:47 INFO - PID 19238 | 04:06:47 INFO - PID 19238 | [#6] imaging-darkroom Cycles:10 Average:149.20 Median:149.00 stddev:2.15 (1.4%) stddev-sans-first:2.03 04:06:47 INFO - PID 19238 | Values: 152.0 148.0 154.0 149.0 147.0 148.0 148.0 148.0 149.0 149.0 04:06:47 INFO - PID 19238 | 04:06:47 INFO - PID 19238 | [#7] imaging-desaturate Cycles:10 Average:98.00 Median:96.00 stddev:3.09 (3.2%) stddev-sans-first:3.19 04:06:47 INFO - PID 19238 | Values: 100.0 95.0 105.0 97.0 95.0 97.0 99.0 100.0 96.0 96.0 04:06:47 INFO - PID 19238 | 04:06:47 INFO - PID 19238 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.00 stddev:2.75 (5.2%) stddev-sans-first:2.57 04:06:47 INFO - PID 19238 | Values: 57.0 52.0 52.0 50.0 51.0 54.0 59.0 52.0 53.0 53.0 04:06:47 INFO - PID 19238 | 04:06:47 INFO - PID 19238 | [#9] json-stringify-tinderbox Cycles:10 Average:40.90 Median:40.50 stddev:4.31 (10.6%) stddev-sans-first:4.57 04:06:47 INFO - PID 19238 | Values: 41.0 38.0 37.0 40.0 48.0 36.0 46.0 46.0 40.0 37.0 04:06:47 INFO - PID 19238 | 04:06:47 INFO - PID 19238 | [#10] stanford-crypto-aes Cycles:10 Average:75.90 Median:78.00 stddev:3.54 (4.5%) stddev-sans-first:3.46 04:06:47 INFO - PID 19238 | Values: 72.0 75.0 72.0 71.0 74.0 80.0 80.0 79.0 77.0 79.0 04:06:47 INFO - PID 19238 | 04:06:47 INFO - PID 19238 | [#11] stanford-crypto-ccm Cycles:10 Average:113.90 Median:115.00 stddev:3.54 (3.1%) stddev-sans-first:3.73 04:06:47 INFO - PID 19238 | Values: 115.0 117.0 114.0 119.0 111.0 115.0 113.0 117.0 111.0 107.0 04:06:47 INFO - PID 19238 | 04:06:47 INFO - PID 19238 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.00 Median:165.00 stddev:2.45 (1.5%) stddev-sans-first:2.57 04:06:47 INFO - PID 19238 | Values: 165.0 162.0 165.0 163.0 169.0 162.0 165.0 161.0 162.0 166.0 04:06:47 INFO - PID 19238 | 04:06:47 INFO - PID 19238 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.00 stddev:1.85 (2.9%) stddev-sans-first:1.59 04:06:47 INFO - PID 19238 | Values: 68.0 65.0 63.0 63.0 68.0 65.0 65.0 64.0 63.0 65.0 04:06:47 INFO - PID 19238 | -------- Summary: end -------- 04:06:47 INFO - PID 19238 | 04:06:48 INFO - PID 19238 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:06:48 INFO - TEST-INFO | 19238: exit 0 04:06:48 INFO - TEST-OK | kraken | took 62640ms 04:06:48 INFO - SUITE-END | took 1174s 04:06:49 INFO - Completed test suite (00:19:34) 04:06:49 INFO - javascript benchmark 04:06:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8491.516438747292, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3182.0612919188843, 3182.236263296808, 3189.776715629907, 3207.5830142081527, 3253.6095668519765, 6120.14317995481, 6147.723352449139, 6194.403930489669, 6281.0, 6338.492920566361, 6152.5693201475915, 6176.258848938133, 6191.969040154793, 6212.223472065992, 6240.719167637456, 5696.060150075243, 5739.684317362543, 5771.105478650816, 5789.739461724233, 5845.503132233765, 5741.626794258381, 5804.825855224329, 5844.707764611774, 5850.122481627747, 5853.9170253503025, 77.8064072480504, 5683.374828768832, 5773.076307790754, 5857.502112320437, 5865.736041878125, 5269.499397557226, 5627.971860140714, 5723.227364305807, 5745.712714364265, 5837.64974101551, 5680.147977803321, 5685.971570142165, 5716.371199168088, 5751.654900705971, 5801.912971305392, 6106.6336019838955, 6170.598911070766, 6219.259097821082, 6223.751049957997, 6290.276618188921, 6656.800295991128, 6662.0, 6705.396514313687, 6725.05849181115, 6750.696218670122, 6535.117759102508, 6706.329367063304, 6720.227173875018, 6795.422389096932, 6805.0, 6174.0, 6180.258368995749, 6184.443400094013, 6191.876162476725, 6195.7521699131985, 6750.189977202767, 6803.115594972622, 6817.863642727168, 6825.658717064127, 6829.453643708492, 6481.254655714653, 6482.805515834532, 6543.247526534415, 6551.246606640298, 6564.409203171737, 6609.372109649576, 6694.828405029198, 6702.296258892844, 6764.661766911635, 6790.5246632736225, 6616.900746488806, 6645.933540664628, 6669.333066693389, 6735.797926062226, 6737.225219099768, 6418.454431373386, 6694.364035416627, 6702.631355275506, 6768.83077923046, 6780.966095169591, 5205.9219111713355, 5212.9739351303, 5308.654937429054, 5311.760970756325, 5343.625946183807, 4599.149157405824, 4629.0, 4665.743384113906, 4670.7664616769025, 4671.649626278043, 5656.575756818255, 5705.5720820938595, 5744.281964754406, 5776.451237132465, 5777.62445441045, 6439.452646525004, 6629.668516574152, 6660.833479162963, 6711.798646040626, 6725.730970761213, 5615.129654903497, 5663.745131469093, 5668.943310566924, 5671.716414179275, 5705.115707065412, 5772.047612144033, 5783.566232532577, 5789.334226563915, 5802.216700745345, 5819.88360232793, 6464.709088091047, 6488.448481878999, 6533.640649764308, 6534.673266336664, 6620.834479137964, 6619.966900165468, 6624.205095388585, 6673.599584024974, 6716.664166791641, 6759.121314229119, 6226.40849119333, 6400.967995159995, 6410.647414392253, 6419.2938776735455, 6428.550001500037, 6432.6462044588, 6460.612363258031, 6489.935100648933, 6521.315261897622, 6532.085508028975, 6599.53803233769, 6625.801225963229, 6630.038644396439, 6645.0696902434665, 6647.634380109141, 6480.0, 6528.053432252392, 6535.183102112236, 6593.142891424084, 6599.373059559334, 6407.583507072025, 6461.06314584392, 6564.5076619254705, 6569.507286953402, 6584.670766461562, 6217.813465596039, 6342.460890824332, 6348.888944434613, 6387.712552935222, 6401.42387185137, 6442.355764423708, 6470.288268290578, 6491.902621460587, 6503.382178693206, 6543.345665433609, 6188.226471691039, 6246.968765156145, 6273.309935906956, 6283.214598175228, 6306.117143599991], "value": 6033.443669773831, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [878.4509681449094, 899.3030401438884, 902.021306882033, 902.5171533229724, 903.8779764731768, 39735.0, 40598.0, 43516.564834351644, 44011.11977760451, 44073.11853762923, 39315.41026884599, 39475.80262098693, 39781.0, 39789.40315895264, 39884.0, 7244.203137654854, 7425.368843648296, 7434.145073316584, 7908.367330613537, 8086.474379165365, 7957.482763620375, 8038.799030024237, 8059.07320658123, 8064.27421532062, 8066.838663226732, 8048.637811298506, 8118.715844945428, 8133.471324363927, 8140.308073813703, 8145.389095817808, 8063.354931605458, 8090.352771778244, 8102.635381407792, 8104.270615644561, 8106.027276726831, 7947.72182973599, 7997.840043199161, 8004.959975200145, 8015.879761803578, 8016.67933282668, 38471.61528384708, 38627.420588691195, 38899.0, 39005.21989560193, 39098.804505977576, 3534.0634731796094, 3566.0193446802077, 3570.6786389225094, 3572.7856328620364, 3573.0352804742834, 32309.35381292361, 32368.676313237036, 32745.83627081873, 32772.0, 32803.50794738081, 5222.477752224785, 5256.448072952323, 5296.020236256305, 5318.734063296858, 5354.116570765819, 185017.0, 185074.14925850704, 185428.0, 185739.0, 185792.0, 43450.0, 49768.75115624471, 49799.75100124476, 50070.0, 50370.24444633334, 46218.0, 51223.0, 52211.0, 52531.0, 52592.73703631534, 15185.240737963057, 15203.923980380026, 15256.618584535254, 15340.232988350537, 15377.0, 12256.44845981933, 12320.75358492843, 12391.75216495665, 12497.062720296015, 12536.561220357244, 14154.929225353808, 14305.499307524187, 14369.56891293263, 14457.566273011826, 14511.927440362731, 5687.203791469197, 5734.856628584319, 5813.912791308134, 5880.0, 5968.403159684084, 993.9204863611038, 1005.7133716890651, 1018.2770233134557, 1030.160419258302, 1041.6770801051546, 23566.821658916986, 23645.0, 23664.7633523666, 23672.408189795395, 23689.763102368757, 1078.9568417263379, 1120.5797825815318, 1129.1136457880689, 1132.9546818127346, 1136.0627482326947, 12086.214396064228, 12526.436310366056, 12616.0, 12617.558385456465, 12793.680157996125, 225681.0, 227569.0, 228041.0, 228831.0, 230461.0, 54732.0, 54772.0, 54924.17613735797, 55004.72497637486, 55364.72317638386, 1418.2625034981752, 1466.5673626280272, 1467.501049643105, 1483.666175110613, 1488.092263719152, 22202.777972220716, 23100.422489437897, 23130.768692313533, 23135.65296520587, 23208.18771342995, 38444.807775960944, 38550.42174367331, 38564.6143538561, 38601.227975439775, 38821.8058909715, 194310.0, 194764.0, 194884.0, 196233.0, 196358.0, 29886.25284367908, 31722.682773172895, 32176.35647287088, 32267.35465290728, 35150.0, 43820.56179438292, 43980.34029489496, 44450.333245001995, 44645.55354446544, 44860.551394486945, 32426.513602296458, 32606.34787304288, 32983.34033319272, 33117.66882331242, 33600.663993359754, 31991.520127198575, 32086.588703657628, 32495.837520812245, 32508.18729531686, 33266.833665832484, 36630.63369366272, 36653.63346366502, 36811.07972300714, 37010.0, 37059.8147009274, 31105.8444707775, 31479.84260078685, 31515.684843151274, 31558.0, 31624.36751265008, 24087.638685420083, 24213.0, 24519.632205516577, 24565.631515526922, 25011.12461063854], "value": 17327.96193921484, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.8421933968161, 584.2900875436344, 591.1694069831888, 596.8507873031742, 610.8198081565939, 22018.229361972335, 22820.65769013466, 23098.88450557749, 23166.420839479022, 23186.42033949152, 24601.0, 24709.258722238315, 26072.739272607316, 26254.868725656346, 26340.868295658496, 6494.285628580864, 6621.536492445531, 6651.634160121192, 6686.598804071771, 6689.531732778683, 6453.9677301613665, 6610.834729131786, 6702.731890724365, 6704.1954965403975, 6714.731410743564, 6610.636414997173, 6680.265170831205, 6680.7327706891665, 6680.76617318394, 6694.631795251284, 6565.0, 6606.075149479053, 6637.269900310937, 6678.833029174261, 6686.966565167143, 6203.906941395882, 6223.533235007392, 6235.844103897393, 6237.656928868911, 6256.343083976162, 29484.0, 29867.40265194684, 30565.388692226254, 30581.847090764626, 30748.38503229945, 2069.244725675118, 2069.3584988653415, 2111.7043613894066, 2111.862728922615, 2117.2589593642256, 19411.514712132168, 20015.399538013884, 20015.89992050045, 20031.599368012558, 20088.799112008837, 2395.4490467192645, 2428.8542687438635, 2488.6764720586207, 2489.21589699246, 2491.862947537885, 115838.0, 121321.39339303247, 138269.0, 138699.6130038697, 146054.26972865174, 14415.625843496226, 14902.850971490363, 15010.849891500946, 15027.09837409759, 15593.532194034196, 24622.384440388774, 24645.38386540315, 24686.506269874502, 24716.38209044788, 24865.87567062153, 9744.366616169926, 9999.55002024896, 10021.148202402732, 10029.849552256723, 10169.847452288222, 9001.729948101567, 9046.86429703555, 9052.958909725334, 9178.173964343241, 9220.44677319363, 9034.774130646787, 9139.680111196076, 9153.496557689392, 9187.356885018118, 9368.484733339732, 2665.3869609989424, 2683.1011611110107, 2744.108164846433, 2803.327201471632, 2836.8156069855395, 949.5679465843162, 969.418348990614, 975.2588033094898, 979.9216062714966, 989.7525618595351, 20181.697274540893, 20245.595088098155, 20385.286514971904, 20415.387538373874, 20527.58944821095, 1030.685640879524, 1031.2729525684374, 1032.3754128752148, 1042.155853758458, 1054.6097943760858, 11154.0, 11288.661340159644, 11372.14708896836, 11388.3167009978, 11404.600838970597, 469319.0, 481191.0, 504017.0, 506638.0, 515029.0, 111058.0, 112777.0, 117820.0, 117827.41086294514, 118154.40922795502, 1170.0931777872217, 1180.2741314091943, 1187.2342339191173, 1194.0387987669835, 1197.958071467512, 18217.089145542406, 18353.3576324828, 18521.907390462962, 18731.90634046821, 18826.435206943814, 51759.48240517547, 52788.20817687814, 53696.92606147933, 54018.0, 55201.72399137979, 66654.66672666605, 66906.33093669, 68344.3165568338, 68514.31485685278, 69724.6513767428, 14754.262286885827, 14986.625334366729, 15117.924410377878, 15136.470223542123, 15272.694546109238, 23555.0, 23817.880910596028, 24170.154044608353, 24256.1510347137, 24341.756582433947, 18616.06919654039, 18670.90664546723, 18685.626287474446, 18839.71740423922, 19190.656654034643, 16301.184940752677, 16327.265273062978, 16786.58033549122, 17188.31246750166, 17198.22607982619, 18812.341568045053, 18870.433886983414, 18888.0, 18908.0, 18946.810531894505, 14653.900957428023, 14927.776083358542, 14968.176750278404, 15010.0, 15047.47333843354, 18266.908665456587, 18435.72346414778, 18448.35430759917, 18514.351997680016, 18567.0], "value": 12089.302589685874, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1340.3968214303563, 1380.9240491772948, 1382.4262930883683, 1387.6947071644236, 1390.652336915771, 14921.626959326022, 15555.0, 15574.688506229897, 15577.454789082385, 15678.76481852773, 15262.0, 15318.310676019579, 15349.0, 15358.0, 15372.23138843059, 2609.621604867293, 2719.075514325129, 2741.300968253093, 2755.9035433759823, 2756.4073724149303, 2659.5478768609382, 2723.360010397555, 2725.318670332417, 2737.507248695234, 2738.6850512191054, 2654.7345265473396, 2738.4523095381005, 2743.9588406173825, 2768.418632087254, 2773.126465163477, 2710.268227578553, 2723.4416944526324, 2763.8618069096665, 2764.8064635475525, 2781.6105745195687, 2450.534398464286, 2673.42521357909, 2687.6371689821817, 2688.8790004449847, 2702.4189799193073, 15163.09021458716, 15167.848321516754, 15262.236888155549, 15272.236388180658, 15292.311845966959, 7950.165232650604, 8099.635516401776, 8187.345012398994, 8226.05400378958, 8228.958855205745, 5070.467600901926, 5332.840014799563, 5364.302640656729, 5386.165144402624, 5428.5928555358505, 1989.383291179739, 1992.3126521350111, 2003.5892642008498, 2044.6217449771839, 2046.5190680190144, 25904.87047564769, 26219.21342359732, 26353.868230658914, 26385.07652232182, 26461.280016798846, 6583.374579415044, 6810.965945170342, 6816.182058153054, 6917.13535808024, 6973.232944376115, 17314.220860061327, 17336.479905602853, 17364.305427783, 17380.739288910678, 17386.913065434594, 7677.961610191914, 7989.560574168477, 8017.358611311081, 8028.598570071473, 8034.558099304478, 7207.171175314801, 7315.743948961761, 7336.596487193256, 7468.10382754073, 7478.551286922802, 4929.901401971941, 5117.488251174853, 5122.769475373617, 5139.331886854685, 5159.561437277874, 1471.257015207335, 1496.5959191018364, 1504.811898512686, 1509.5546813689987, 1531.0124969394678, 729.4711334282603, 825.3314814999869, 868.847951608466, 879.6965047058754, 895.3329769321766, 7723.7682869514, 7876.645550950221, 7945.284924356835, 7962.124166341698, 8074.434789564674, 965.5172413793103, 986.541258314887, 1007.6825799873017, 1017.0, 1020.9744756381001, 4712.316714076508, 4810.0, 4883.536064073907, 4968.279599458058, 5175.818846340435, 21077.683834742493, 21472.0, 21742.347729568137, 21849.781502184775, 22060.779392206194, 21647.567048658937, 21673.45816354572, 22317.442063948205, 22475.325740227818, 22754.43113922165, 10687.0, 11136.0, 11197.272177308449, 11230.663080107774, 11279.548818047351, 7377.446691498267, 7744.92255077442, 7765.961170194113, 7793.337566306927, 7833.0, 18688.159032843283, 19035.428937131906, 19197.90401047986, 19357.90321048442, 19560.119794608992, 18706.532336691693, 19664.311749088713, 19946.0, 19959.501012474804, 20344.491387715425, 10996.945015274872, 11087.0, 11111.333320000826, 11171.832422513831, 11173.329600224013, 14034.368453419416, 14187.716245674823, 14214.502492412716, 14238.0, 14240.715185696436, 11260.211785174959, 11582.131340149692, 11706.531738730355, 11975.94012029969, 12095.092868034757, 11390.943045284721, 11499.94250028778, 11534.077273818242, 11640.0, 11674.649760507198, 10404.73988150272, 10512.053915147673, 10521.526531305957, 10533.525991330562, 10541.367517948947, 7005.159380874327, 7067.469939754436, 7079.610621415872, 7158.749443769443, 7167.498275120692, 6933.445324373937, 7035.753748618774, 7051.294870513113, 7106.751263705745, 7140.285971402818], "value": 6684.375834338185, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.6898226572926, 525.3721802446078, 544.186441270301, 553.2724467325467, 561.6152935239361, 6147.784827531037, 6177.969110154443, 6194.194754681891, 6218.689065546727, 6239.4696450801675, 6083.878322433548, 6091.512678985681, 6110.464157374684, 6183.567150299481, 6184.505239580834, 5110.795568177268, 5111.974440127794, 5114.3095682083, 5117.641765076446, 5127.384713834345, 5009.874753131165, 5082.2122571001555, 5122.795088196468, 5128.8974220515565, 5180.740962951856, 4912.238603016538, 4958.7024778513405, 4980.77586508608, 5004.549590536851, 5028.957480724164, 5006.6245031622775, 5021.598272138239, 5047.4447810740785, 5049.64652474327, 5075.568576671006, 4624.329472226542, 4654.488006319302, 4671.953280467186, 4696.7651617419, 4713.9764301178275, 5925.140854576105, 5982.491488223506, 6000.909986350208, 6022.277326720821, 6052.606580572248, 5723.742431590588, 5763.077907534775, 5781.595288329819, 5834.474897259224, 5853.473187413153, 6330.987042073292, 6336.429721325103, 6399.520035997319, 6423.7430502779835, 6426.582272152295, 4893.779779909913, 4980.626453016038, 5027.09512287788, 5061.0, 5065.645404821664, 6483.643399613019, 6616.867662646769, 6657.633830139341, 6717.630530320879, 6733.764318248838, 5346.759395827197, 5347.331583552056, 5356.143017117242, 5364.14173732201, 5367.946320536823, 5369.7583608737705, 5390.676559406448, 5431.076716958127, 5487.64330318528, 5488.039593071197, 5483.4516548344845, 5568.860778480571, 5573.303337082864, 5751.281089863767, 5871.941280587225, 6404.519661025442, 6433.646149461731, 6472.3204063573585, 6483.384078512534, 6513.534454747739, 1710.4868539438119, 1717.544850614588, 1731.5497970527754, 1745.9698777721205, 1746.0920321432784, 1183.3432444992939, 1219.359836086062, 1236.863944966053, 1241.888230059299, 1245.975080498403, 5542.91685624716, 5543.52880005204, 5549.889002219933, 5560.888782224333, 5599.768051028767, 6449.32282110381, 6476.384743449365, 6515.283318834924, 6562.146920900253, 6585.308542603054, 5437.483439073281, 5500.744694675244, 5518.475744804237, 5527.53015993645, 5571.1086226203615, 3364.360771453465, 3398.4222682144095, 3439.0, 3457.619661837196, 3473.89578312651, 6294.716737746812, 6318.210223722035, 6375.0, 6391.009393544008, 6440.162778838721, 6620.2386725527185, 6620.775156595949, 6630.574426498326, 6636.535442518977, 6690.13028306317, 6272.153259310117, 6318.1872677136225, 6324.3043265240785, 6333.651649159248, 6371.362863713592, 6358.107061287011, 6386.68066596659, 6413.807585772434, 6444.0, 6457.192850893638, 5920.319163296103, 6364.395382438846, 6403.935960640334, 6411.166548348685, 6505.934940650533, 6097.878042439215, 6217.440430361289, 6254.155688981929, 6269.216347956505, 6280.748770049329, 4755.334253204624, 4909.754512274443, 4942.4810394909455, 4948.703077815343, 5008.749562521786, 6575.178102737158, 6584.275729669636, 6592.2089349276475, 6601.405873471411, 6611.66941652925, 6266.624002559861, 6269.592476489013, 6292.370762923671, 6364.395382438846, 6380.266269379081, 6289.339619339903, 6306.0, 6347.904781428375, 6358.427741503287, 6400.967995159995, 6203.627782333075, 6211.720472578835, 6250.874982500233, 6269.247690277196, 6284.748610055546, 6350.872982540416, 6381.649009304347, 6390.360963903572, 6469.45009674183, 6494.707738151889, 5971.253593300838, 6146.770645870754, 6148.692565371624, 6170.79669464468, 6192.411720886508], "value": 5007.1644478042945, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [896.4979611417602, 904.3804993579398, 914.6615752171698, 915.8763566918468, 920.1166879795396, 18430.815691843076, 19102.14040368183, 19110.853348799064, 19111.904440477814, 19160.71258931117, 18580.907095464536, 18915.52711182221, 18939.33712320069, 18945.33691320804, 18973.810261897375, 905.0225756183339, 914.3005600715458, 924.523870206844, 926.1155596405416, 927.0590350793949, 980.8283550378691, 993.8608594796734, 994.2890833054365, 994.6916455898659, 995.8257304971637, 980.901909809017, 1010.1111022300365, 1010.8584798128268, 1017.3946501831435, 1031.417249254169, 994.4381424495137, 1005.1355833982769, 1005.8994100589956, 1007.0231875081196, 1012.4431562640518, 727.2182403915759, 728.5774250934498, 730.4777084384638, 734.8824188129927, 737.7159793479495, 19248.903755481133, 20239.79760202394, 20786.896065519726, 20975.685364719542, 20979.475513112142, 21579.784202157934, 21756.238531651474, 21935.0, 22292.331230062962, 22432.88783556088, 17025.404110856183, 17222.397216097375, 17275.74086388705, 17277.913610431868, 17351.566210844703, 3719.7396182267253, 3752.2683076800035, 3761.680257178143, 3767.2277183177657, 3780.281746468162, 108983.0, 128715.0, 140732.0, 141001.0, 142749.2862535691, 17529.386471473692, 17544.736828947574, 17712.46862594124, 17934.282628694706, 18022.5494362642, 30241.0, 30673.539896901566, 30919.2270193247, 31063.378732425226, 31072.844635776677, 8812.779680508038, 8987.146221108984, 8988.280937524982, 9031.954840225757, 9036.27709783229, 4194.706370554093, 4272.33778764292, 4289.24938135825, 4294.0767734937135, 4304.053108316165, 5348.689571055116, 5424.267723857229, 5448.700321482356, 5471.316085489314, 5509.586780991442, 1002.9247806414548, 1035.7773078787945, 1045.9529321180412, 1046.2205656785743, 1061.936283822973, 666.466826538767, 674.6491824251361, 692.069166970424, 698.7135274537416, 701.0290747315006, 11632.941835290769, 11909.821352679717, 12097.153199275966, 12373.690657733448, 12457.626271211697, 669.5614372585964, 670.5406796344422, 678.9083473730983, 685.6640246279287, 689.9068625735562, 5658.292713410824, 5694.373618901917, 5749.626274292241, 5767.423257674283, 5872.794452194238, 120604.0, 123595.3820230893, 124535.0, 126211.0, 129675.35162324317, 99446.0, 101739.0, 101984.0, 102383.0, 103190.0, 83749.16250837536, 91588.54205728929, 91828.54085729663, 92703.53648231715, 94626.0, 10543.472826358498, 10619.840702389625, 10694.358338499715, 10742.086922611665, 10814.45927703596, 43095.35356969585, 44251.33622995593, 44690.55309446864, 44725.32912006388, 44809.0, 53713.19430208472, 54470.72764636151, 55422.44577554173, 55514.16728748991, 55647.443525565846, 14572.198529081003, 14603.926980365455, 14704.411823527367, 14882.404703811726, 14914.477993270184, 17139.31442742326, 17178.226979786188, 17219.0, 17322.82677173212, 17601.383951561635, 15475.148866811984, 15501.0, 15591.06453612787, 15608.0, 15771.605709857346, 15280.312385942438, 15310.617234569225, 15372.692546149237, 15507.379704811681, 15630.843691562939, 14863.925680371529, 15075.623109422353, 15335.846641533888, 15542.5337239883, 15973.760393594337, 10713.608747269021, 10802.405867677353, 10941.45292735344, 11241.381724005258, 11462.0, 12356.505739770308, 12538.93730531378, 12587.937060315006, 12591.24452532851, 12703.42834572428], "value": 8861.719683533092, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1441.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 115.0, 112.0, 113.0, 107.0, 113.0, 106.0, 108.0, 115.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 119.0, 125.0, 117.0, 125.0, 118.0, 125.0, 115.0, 127.0, 120.0], "value": 122.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 169.0, 165.0, 163.0, 163.0, 177.0, 176.0, 165.0, 175.0, 172.0], "value": 168.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 83.0, 75.0, 85.0, 80.0, 79.0, 76.0, 79.0, 79.0], "value": 79.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 87.0, 82.0, 83.0, 81.0, 86.0, 84.0, 85.0, 81.0], "value": 83.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 117.0, 123.0, 118.0, 114.0, 120.0, 114.0, 116.0, 114.0, 115.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 148.0, 154.0, 149.0, 147.0, 148.0, 148.0, 148.0, 149.0, 149.0], "value": 149.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 95.0, 105.0, 97.0, 95.0, 97.0, 99.0, 100.0, 96.0, 96.0], "value": 98.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 52.0, 52.0, 50.0, 51.0, 54.0, 59.0, 52.0, 53.0, 53.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [41.0, 38.0, 37.0, 40.0, 48.0, 36.0, 46.0, 46.0, 40.0, 37.0], "value": 40.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 75.0, 72.0, 71.0, 74.0, 80.0, 80.0, 79.0, 77.0, 79.0], "value": 75.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 117.0, 114.0, 119.0, 111.0, 115.0, 113.0, 117.0, 111.0, 107.0], "value": 113.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 165.0, 163.0, 169.0, 162.0, 165.0, 161.0, 162.0, 166.0], "value": 164.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 63.0, 63.0, 68.0, 65.0, 65.0, 64.0, 63.0, 65.0], "value": 64.9, "unit": "score"}]}]} 04:06:49 INFO - Return code: 0 04:06:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:06:49 INFO - # TBPL SUCCESS # 04:06:49 INFO - Running post-action listener: _package_coverage_data 04:06:49 INFO - Running post-action listener: _resource_record_post_action 04:06:49 INFO - [mozharness: 2017-09-28 11:06:49.155917Z] Finished run-tests step (success) 04:06:49 INFO - Running post-run listener: _resource_record_post_run 04:06:49 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 344154112; Read time: 20; Write time: 190024 04:06:49 INFO - TinderboxPrint: CPU usage
13.2% 04:06:49 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 04:06:49 INFO - TinderboxPrint: I/O write bytes / time
344,154,112 / 190,024 04:06:49 INFO - TinderboxPrint: CPU idle
8,250.2 (86.8%) 04:06:49 INFO - TinderboxPrint: CPU user
1,198.6 (12.6%) 04:06:49 INFO - TinderboxPrint: Swap in / out
0 / 0 04:06:49 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13058048; Read time: 0; Write time: 2160 04:06:49 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:06:49 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 331096064; Read time: 0; Write time: 187864 04:06:49 INFO - Running post-run listener: _upload_blobber_files 04:06:49 INFO - Blob upload gear active. 04:06:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:06:49 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 04:06:49 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'] 04:06:49 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 04:06:50 INFO - (blobuploader) - INFO - Open directory for files ... 04:06:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:06:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:06:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:06:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:06:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:06:51 INFO - (blobuploader) - INFO - Done attempting. 04:06:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:06:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:06:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:06:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:06:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:06:54 INFO - (blobuploader) - INFO - Done attempting. 04:06:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:06:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:06:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:06:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:06:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:06:55 INFO - (blobuploader) - INFO - Done attempting. 04:06:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:06:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:06:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:06:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:06:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:06:56 INFO - (blobuploader) - INFO - Done attempting. 04:06:56 INFO - (blobuploader) - INFO - Iteration through files over. 04:06:56 INFO - Return code: 0 04:06:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:06:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:06:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf4d1b59bdaf6ec65608989d5a2d6f14497e04f7f50c47627cd69488fde956c6ff05314343e605e14417e728cd2cc5ccb1e7f090a67ae5e123ed9f3afc8ed85c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78529fbdc13e13786cf8301c07502a3907c9b12c271ff75596af758fc0b638a7330789ccd39fac5c58de9b67690d1dcd0ced7d3e9abe4bc1b4cef9b0d25924c8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8af2b061669625ea259d4a2c15627f5a166038a09cbe1375a2312304accf3b49149ebbc638e08333180c1b56410abd8e59ed08a0bd18055ee350bf3b0489c7ed", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83fb183d43e61f42e13e457b93be609f08ad76e98166eb0203f35c7bdd8acad9dcf77ad3f429877e5a26eeff20baece3967c8a404f3f61bd9b72e4583c29569e"} 04:06:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:06:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:06:56 INFO - Contents: 04:06:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf4d1b59bdaf6ec65608989d5a2d6f14497e04f7f50c47627cd69488fde956c6ff05314343e605e14417e728cd2cc5ccb1e7f090a67ae5e123ed9f3afc8ed85c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78529fbdc13e13786cf8301c07502a3907c9b12c271ff75596af758fc0b638a7330789ccd39fac5c58de9b67690d1dcd0ced7d3e9abe4bc1b4cef9b0d25924c8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8af2b061669625ea259d4a2c15627f5a166038a09cbe1375a2312304accf3b49149ebbc638e08333180c1b56410abd8e59ed08a0bd18055ee350bf3b0489c7ed", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83fb183d43e61f42e13e457b93be609f08ad76e98166eb0203f35c7bdd8acad9dcf77ad3f429877e5a26eeff20baece3967c8a404f3f61bd9b72e4583c29569e"} 04:06:56 INFO - Running post-run listener: copy_logs_to_upload_dir 04:06:56 INFO - Copying logs to upload dir... 04:06:56 INFO - mkdir: /builds/slave/test/build/upload/logs 04:06:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.739534 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-09-28 04:06:56.705650) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-28 04:06:56.709044) ========= 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=41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425 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/cf4d1b59bdaf6ec65608989d5a2d6f14497e04f7f50c47627cd69488fde956c6ff05314343e605e14417e728cd2cc5ccb1e7f090a67ae5e123ed9f3afc8ed85c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78529fbdc13e13786cf8301c07502a3907c9b12c271ff75596af758fc0b638a7330789ccd39fac5c58de9b67690d1dcd0ced7d3e9abe4bc1b4cef9b0d25924c8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8af2b061669625ea259d4a2c15627f5a166038a09cbe1375a2312304accf3b49149ebbc638e08333180c1b56410abd8e59ed08a0bd18055ee350bf3b0489c7ed", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83fb183d43e61f42e13e457b93be609f08ad76e98166eb0203f35c7bdd8acad9dcf77ad3f429877e5a26eeff20baece3967c8a404f3f61bd9b72e4583c29569e"} build_url:https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011271 build_url: 'https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf4d1b59bdaf6ec65608989d5a2d6f14497e04f7f50c47627cd69488fde956c6ff05314343e605e14417e728cd2cc5ccb1e7f090a67ae5e123ed9f3afc8ed85c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78529fbdc13e13786cf8301c07502a3907c9b12c271ff75596af758fc0b638a7330789ccd39fac5c58de9b67690d1dcd0ced7d3e9abe4bc1b4cef9b0d25924c8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8af2b061669625ea259d4a2c15627f5a166038a09cbe1375a2312304accf3b49149ebbc638e08333180c1b56410abd8e59ed08a0bd18055ee350bf3b0489c7ed", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83fb183d43e61f42e13e457b93be609f08ad76e98166eb0203f35c7bdd8acad9dcf77ad3f429877e5a26eeff20baece3967c8a404f3f61bd9b72e4583c29569e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-28 04:06:56.738096) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 04:06:56.738426) ========= 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=41a7e32711b8e08598a63237000001d6-1506595576.467778-1279795425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004480 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 04:06:56.821549) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-28 04:06:56.821866) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-28 04:06:56.822230) ========= ========= Total master_lag: 0.19 =========