builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-058 starttime: 1504520175.14 results: success (0) revision: 632e42dca494ec3d90b70325d9c359f80cb3f38a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:15.136122) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:15.136553) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:15.136898) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:15.151418) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:15.151731) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-04 03:16:15-- 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% 110M=0s 2017-09-04 03:16:15 (110 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.259116 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:15.427738) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:15.428056) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014299 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:15.477482) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:15.477780) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 632e42dca494ec3d90b70325d9c359f80cb3f38a --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 632e42dca494ec3d90b70325d9c359f80cb3f38a --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-04 03:16:15,575 truncating revision to first 12 chars 2017-09-04 03:16:15,575 Setting DEBUG logging. 2017-09-04 03:16:15,575 attempt 1/10 2017-09-04 03:16:15,575 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/632e42dca494?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-04 03:16:16,069 unpacking tar archive at: mozilla-central-632e42dca494/testing/mozharness/ program finished with exit code 0 elapsedTime=0.807991 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:16.301793) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:16.302165) ========= script_repo_revision: 632e42dca494ec3d90b70325d9c359f80cb3f38a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:16.302526) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:16.302787) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:16.315909) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-09-04 03:16:16.316173) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:16:16 INFO - MultiFileLogger online at 20170904 03:16:16 in /builds/slave/test 03:16:16 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:16:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:16:16 INFO - {'append_to_log': False, 03:16:16 INFO - 'base_work_dir': '/builds/slave/test', 03:16:16 INFO - 'blob_upload_branch': 'Firefox', 03:16:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:16:16 INFO - 'branch': 'Firefox', 03:16:16 INFO - 'buildbot_json_path': 'buildprops.json', 03:16:16 INFO - 'code_coverage': False, 03:16:16 INFO - 'config_files': ('talos/linux_config.py',), 03:16:16 INFO - 'default_actions': ('clobber', 03:16:16 INFO - 'read-buildbot-config', 03:16:16 INFO - 'download-and-extract', 03:16:16 INFO - 'populate-webroot', 03:16:16 INFO - 'create-virtualenv', 03:16:16 INFO - 'install', 03:16:16 INFO - 'setup-mitmproxy', 03:16:16 INFO - 'run-tests'), 03:16:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:16:16 INFO - 'disable_ccov_upload': False, 03:16:16 INFO - 'disable_stylo': False, 03:16:16 INFO - 'download_minidump_stackwalk': True, 03:16:16 INFO - 'download_symbols': 'ondemand', 03:16:16 INFO - 'enable_stylo': False, 03:16:16 INFO - 'enable_webrender': False, 03:16:16 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:16:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:16:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:16:16 INFO - 'gecko_profile': False, 03:16:16 INFO - 'gecko_profile_interval': 0, 03:16:16 INFO - 'installer_path': 'installer.exe', 03:16:16 INFO - 'log_level': 'info', 03:16:16 INFO - 'log_name': 'talos', 03:16:16 INFO - 'log_to_console': True, 03:16:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:16:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:16:16 INFO - 'opt_config_files': (), 03:16:16 INFO - 'pip_index': False, 03:16:16 INFO - 'suite': 'dromaeojs-e10s', 03:16:16 INFO - 'system_bits': '32', 03:16:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:16:16 INFO - 'title': 'talos-linux64-ix-058', 03:16:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:16:16 INFO - 'use_talos_json': True, 03:16:16 INFO - 'verify': 'False', 03:16:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:16:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:16:16 INFO - 'work_dir': 'build'} 03:16:16 INFO - [mozharness: 2017-09-04 10:16:16.559862Z] Running clobber step. 03:16:16 INFO - Running pre-action listener: _resource_record_pre_action 03:16:16 INFO - Running main action method: clobber 03:16:16 INFO - rmtree: /builds/slave/test/build 03:16:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:16:17 INFO - Running post-action listener: _resource_record_post_action 03:16:17 INFO - [mozharness: 2017-09-04 10:16:17.579746Z] Finished clobber step (success) 03:16:17 INFO - [mozharness: 2017-09-04 10:16:17.579844Z] Running read-buildbot-config step. 03:16:17 INFO - Running pre-action listener: _resource_record_pre_action 03:16:17 INFO - Running main action method: read_buildbot_config 03:16:17 INFO - Using buildbot properties: 03:16:17 INFO - { 03:16:17 INFO - "project": "", 03:16:17 INFO - "product": "firefox", 03:16:17 INFO - "who": "archaeopteryx@coole-files.de", 03:16:17 INFO - "installer_path": "public/build/target.tar.bz2", 03:16:17 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 03:16:17 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:16:17 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 03:16:17 INFO - "stage_platform": "linux64-qr", 03:16:17 INFO - "basedir": "/builds/slave/test", 03:16:17 INFO - "buildnumber": 29, 03:16:17 INFO - "platform": "ubuntu64_hw_qr", 03:16:17 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:16:17 INFO - "slavebuilddir": "test", 03:16:17 INFO - "taskId": "S6XFH6k4TTiDVW8QQuifGQ", 03:16:17 INFO - "branch": "mozilla-central", 03:16:17 INFO - "script_repo_revision": "production", 03:16:17 INFO - "revision": "632e42dca494ec3d90b70325d9c359f80cb3f38a", 03:16:17 INFO - "slavename": "talos-linux64-ix-058", 03:16:17 INFO - "repo_path": "mozilla-central" 03:16:17 INFO - } 03:16:17 INFO - Finding installer, test and symbols from parent task. 03:16:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S6XFH6k4TTiDVW8QQuifGQ'}, attempt #1 03:16:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S6XFH6k4TTiDVW8QQuifGQ'}, attempt #1 03:16:18 INFO - Task dependencies: ZH0GnAa4QqyzRlNalh5SXw 03:16:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw'}, attempt #1 03:16:18 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2 03:16:18 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.test_packages.json 03:16:18 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.crashreporter-symbols.zip 03:16:18 INFO - Running post-action listener: _resource_record_post_action 03:16:18 INFO - [mozharness: 2017-09-04 10:16:18.955848Z] Finished read-buildbot-config step (success) 03:16:18 INFO - [mozharness: 2017-09-04 10:16:18.956065Z] Running download-and-extract step. 03:16:18 INFO - Running pre-action listener: _resource_record_pre_action 03:16:18 INFO - Running main action method: download_and_extract 03:16:18 INFO - mkdir: /builds/slave/test/build/tests 03:16:18 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:16:18 INFO - trying https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.test_packages.json 03:16:18 INFO - Downloading https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:16:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:16:19 INFO - Downloaded 989 bytes. 03:16:19 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:16:19 INFO - Using the following test package requirements: 03:16:19 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:16:19 INFO - u'common': [u'target.common.tests.zip'], 03:16:19 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:16:19 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:16:19 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:16:19 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:16:19 INFO - u'mozbase': [u'target.common.tests.zip'], 03:16:19 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:16:19 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:16:19 INFO - u'web-platform': [u'target.common.tests.zip', 03:16:19 INFO - u'target.web-platform.tests.tar.gz'], 03:16:19 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:16:19 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:16:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.common.tests.zip 03:16:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:16:19 INFO - Fetch https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.common.tests.zip into memory 03:16:20 INFO - Content-Length response header: 55022446 03:16:20 INFO - Bytes received: 55022446 03:16:31 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:16:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.common.tests.zip 03:16:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:16:31 INFO - Fetch https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.common.tests.zip into memory 03:16:32 INFO - Content-Length response header: 55022446 03:16:32 INFO - Bytes received: 55022446 03:16:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.talos.tests.zip 03:16:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:16:44 INFO - Fetch https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.talos.tests.zip into memory 03:16:45 INFO - Content-Length response header: 14496650 03:16:45 INFO - Bytes received: 14496650 03:16:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:45 INFO - trying https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2 03:16:45 INFO - Downloading https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:16:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:16:46 INFO - Downloaded 62725116 bytes. 03:16:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2 03:16:46 INFO - mkdir: /builds/slave/test/properties 03:16:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:16:46 INFO - Writing to file /builds/slave/test/properties/build_url 03:16:46 INFO - Contents: 03:16:46 INFO - build_url:https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2 03:16:46 INFO - Running post-action listener: _resource_record_post_action 03:16:46 INFO - Running post-action listener: find_tests_for_verification 03:16:46 INFO - Running post-action listener: set_extra_try_arguments 03:16:46 INFO - [mozharness: 2017-09-04 10:16:46.680110Z] Finished download-and-extract step (success) 03:16:46 INFO - [mozharness: 2017-09-04 10:16:46.680207Z] Running populate-webroot step. 03:16:46 INFO - Running pre-action listener: _resource_record_pre_action 03:16:46 INFO - Running main action method: populate_webroot 03:16:46 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:16:46 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:16:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:16:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:16:46 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:16:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:16:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:16:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:16:46 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:16:46 INFO - 'talos_options': ['--enable-stylo'], 03:16:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:16:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:16:46 INFO - 'tests': ['damp', 'tps']}, 03:16:46 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:16:46 INFO - 'talos_options': ['--enable-stylo'], 03:16:46 INFO - 'tests': ['damp', 'tps']}, 03:16:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:16:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:16:46 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:16:46 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:16:46 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:16:46 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:16:46 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:16:46 INFO - 'talos_options': ['--enable-stylo'], 03:16:46 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:16:46 INFO - 'other-e10s': {'tests': ['a11yr', 03:16:46 INFO - 'ts_paint', 03:16:46 INFO - 'tpaint', 03:16:46 INFO - 'sessionrestore', 03:16:46 INFO - 'sessionrestore_many_windows', 03:16:46 INFO - 'sessionrestore_no_auto_restore', 03:16:46 INFO - 'tabpaint']}, 03:16:46 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:16:46 INFO - 'tests': ['a11yr', 03:16:46 INFO - 'ts_paint', 03:16:46 INFO - 'tpaint', 03:16:46 INFO - 'sessionrestore', 03:16:46 INFO - 'sessionrestore_many_windows', 03:16:46 INFO - 'sessionrestore_no_auto_restore', 03:16:46 INFO - 'tabpaint']}, 03:16:46 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:16:46 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:16:46 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:16:46 INFO - 'tests': ['bloom_basic_singleton']}, 03:16:46 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:16:46 INFO - 'tests': ['bloom_basic']}, 03:16:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:16:46 INFO - 'tsvgr_opacity', 03:16:46 INFO - 'tart', 03:16:46 INFO - 'tscrollx', 03:16:46 INFO - 'tsvg_static']}, 03:16:46 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:16:46 INFO - 'tests': ['tsvgx', 03:16:46 INFO - 'tsvgr_opacity', 03:16:46 INFO - 'tart', 03:16:46 INFO - 'tscrollx', 03:16:46 INFO - 'tsvg_static']}, 03:16:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:16:46 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:16:46 INFO - 'talos_options': ['--enable-stylo'], 03:16:46 INFO - 'tests': ['tp5o']}, 03:16:46 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:16:46 INFO - 'talos_options': ['--mitmproxy', 03:16:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:16:46 INFO - '--firstNonBlankPaint'], 03:16:46 INFO - 'tests': ['tp6_google', 03:16:46 INFO - 'tp6_youtube', 03:16:46 INFO - 'tp6_amazon', 03:16:46 INFO - 'tp6_facebook']}, 03:16:46 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:16:46 INFO - 'talos_options': ['--enable-stylo', 03:16:46 INFO - '--mitmproxy', 03:16:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:16:46 INFO - '--firstNonBlankPaint'], 03:16:46 INFO - 'tests': ['tp6_google', 03:16:46 INFO - 'tp6_youtube', 03:16:46 INFO - 'tp6_amazon', 03:16:46 INFO - 'tp6_facebook']}, 03:16:46 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:16:46 INFO - 'talos_options': ['--enable-stylo', 03:16:46 INFO - '--stylo-threads=1', 03:16:46 INFO - '--mitmproxy', 03:16:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:16:46 INFO - '--firstNonBlankPaint'], 03:16:46 INFO - 'tests': ['tp6_google', 03:16:46 INFO - 'tp6_youtube', 03:16:46 INFO - 'tp6_amazon', 03:16:46 INFO - 'tp6_facebook']}, 03:16:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:16:46 INFO - 'talos_options': ['--xperf_path', 03:16:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:16:46 INFO - 'tests': ['tp5n']}, 03:16:46 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:16:46 INFO - 'talos_options': ['--enable-stylo', 03:16:46 INFO - '--xperf_path', 03:16:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:16:46 INFO - 'tests': ['tp5n']}}} 03:16:46 INFO - Running post-action listener: _resource_record_post_action 03:16:46 INFO - [mozharness: 2017-09-04 10:16:46.694477Z] Finished populate-webroot step (success) 03:16:46 INFO - [mozharness: 2017-09-04 10:16:46.694565Z] Running create-virtualenv step. 03:16:46 INFO - Running pre-action listener: _resource_record_pre_action 03:16:46 INFO - Running main action method: create_virtualenv 03:16:46 INFO - Creating virtualenv /builds/slave/test/build/venv 03:16:46 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:16:46 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:16:46 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:16:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:16:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:16:47 INFO - Installing distribute.............................................................................................................................................................................................done. 03:16:47 INFO - Installing pip...............done. 03:16:47 INFO - Return code: 0 03:16:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:16:47 INFO - Reading from file tmpfile_stdout 03:16:47 INFO - Output received: 03:16:47 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:16:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:16: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:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16: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:16:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21aca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x234fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2353090>, '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 0x2353d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x234df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', '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:16:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:16:47 INFO - Using env: {'DISPLAY': ':0', 03:16:47 INFO - 'HOME': '/home/cltbld', 03:16:47 INFO - 'LANG': 'en_US.UTF-8', 03:16:47 INFO - 'LANGUAGE': 'en_US:en', 03:16:47 INFO - 'LOGNAME': 'cltbld', 03:16:47 INFO - 'MAIL': '/var/mail/cltbld', 03:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:47 INFO - 'MOZ_NO_REMOTE': '1', 03:16:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:47 INFO - 'NO_EM_RESTART': '1', 03:16:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:47 INFO - 'PWD': '/builds/slave/test', 03:16:47 INFO - 'SHELL': '/bin/bash', 03:16:47 INFO - 'SHLVL': '1', 03:16:47 INFO - 'TERM': 'linux', 03:16:47 INFO - 'TMOUT': '86400', 03:16:47 INFO - 'USER': 'cltbld', 03:16:47 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', 03:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:47 INFO - '_': '/tools/buildbot/bin/python'} 03:16:48 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:16:48 INFO - Downloading/unpacking pip>=1.5 03:16:48 INFO - Running setup.py egg_info for package pip 03:16:48 INFO - warning: no files found matching 'pip/cacert.pem' 03:16:48 INFO - warning: no files found matching '*.html' under directory 'docs' 03:16:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:16:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:16:48 INFO - Installing collected packages: pip 03:16:48 INFO - Found existing installation: pip 1.1 03:16:48 INFO - Uninstalling pip: 03:16:48 INFO - Successfully uninstalled pip 03:16:48 INFO - Running setup.py install for pip 03:16:48 INFO - warning: no files found matching 'pip/cacert.pem' 03:16:48 INFO - warning: no files found matching '*.html' under directory 'docs' 03:16:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:16:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:16:48 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:16:48 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:16:48 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:16:48 INFO - Successfully installed pip 03:16:48 INFO - Cleaning up... 03:16:48 INFO - Return code: 0 03:16:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21aca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x234fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2353090>, '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 0x2353d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x234df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', '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:16:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:16:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:16:48 INFO - Using env: {'DISPLAY': ':0', 03:16:48 INFO - 'HOME': '/home/cltbld', 03:16:48 INFO - 'LANG': 'en_US.UTF-8', 03:16:48 INFO - 'LANGUAGE': 'en_US:en', 03:16:48 INFO - 'LOGNAME': 'cltbld', 03:16:48 INFO - 'MAIL': '/var/mail/cltbld', 03:16:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:48 INFO - 'MOZ_NO_REMOTE': '1', 03:16:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:48 INFO - 'NO_EM_RESTART': '1', 03:16:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:48 INFO - 'PWD': '/builds/slave/test', 03:16:48 INFO - 'SHELL': '/bin/bash', 03:16:48 INFO - 'SHLVL': '1', 03:16:48 INFO - 'TERM': 'linux', 03:16:48 INFO - 'TMOUT': '86400', 03:16:48 INFO - 'USER': 'cltbld', 03:16:48 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', 03:16:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:48 INFO - '_': '/tools/buildbot/bin/python'} 03:16:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:49 INFO - Downloading/unpacking psutil>=3.1.1 03:16:49 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:16:49 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:16:49 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:16:49 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:16:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:16:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:16:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:16:50 INFO - Installing collected packages: psutil 03:16:50 INFO - Running setup.py install for psutil 03:16:50 INFO - building 'psutil._psutil_linux' extension 03:16:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:16:50 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:16:50 INFO - building 'psutil._psutil_posix' extension 03:16:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:16:50 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:16:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:16:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:16:50 INFO - Successfully installed psutil 03:16:50 INFO - Cleaning up... 03:16:50 INFO - Return code: 0 03:16:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:16: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:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16: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:16:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21aca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x234fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2353090>, '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 0x2353d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x234df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', '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:16:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:16:50 INFO - Using env: {'DISPLAY': ':0', 03:16:50 INFO - 'HOME': '/home/cltbld', 03:16:50 INFO - 'LANG': 'en_US.UTF-8', 03:16:50 INFO - 'LANGUAGE': 'en_US:en', 03:16:50 INFO - 'LOGNAME': 'cltbld', 03:16:50 INFO - 'MAIL': '/var/mail/cltbld', 03:16:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:50 INFO - 'MOZ_NO_REMOTE': '1', 03:16:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:50 INFO - 'NO_EM_RESTART': '1', 03:16:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:50 INFO - 'PWD': '/builds/slave/test', 03:16:50 INFO - 'SHELL': '/bin/bash', 03:16:50 INFO - 'SHLVL': '1', 03:16:50 INFO - 'TERM': 'linux', 03:16:50 INFO - 'TMOUT': '86400', 03:16:50 INFO - 'USER': 'cltbld', 03:16:50 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', 03:16:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:50 INFO - '_': '/tools/buildbot/bin/python'} 03:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:16: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:16: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:16: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:16: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:16:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:16:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:16:52 INFO - Installing collected packages: mozsystemmonitor 03:16:52 INFO - Running setup.py install for mozsystemmonitor 03:16:52 INFO - Successfully installed mozsystemmonitor 03:16:52 INFO - Cleaning up... 03:16:52 INFO - Return code: 0 03:16:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21aca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x234fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2353090>, '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 0x2353d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x234df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', '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:16:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:16:52 INFO - Using env: {'DISPLAY': ':0', 03:16:52 INFO - 'HOME': '/home/cltbld', 03:16:52 INFO - 'LANG': 'en_US.UTF-8', 03:16:52 INFO - 'LANGUAGE': 'en_US:en', 03:16:52 INFO - 'LOGNAME': 'cltbld', 03:16:52 INFO - 'MAIL': '/var/mail/cltbld', 03:16:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:52 INFO - 'MOZ_NO_REMOTE': '1', 03:16:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:52 INFO - 'NO_EM_RESTART': '1', 03:16:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:52 INFO - 'PWD': '/builds/slave/test', 03:16:52 INFO - 'SHELL': '/bin/bash', 03:16:52 INFO - 'SHLVL': '1', 03:16:52 INFO - 'TERM': 'linux', 03:16:52 INFO - 'TMOUT': '86400', 03:16:52 INFO - 'USER': 'cltbld', 03:16:52 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', 03:16:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:52 INFO - '_': '/tools/buildbot/bin/python'} 03:16:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:52 INFO - Downloading/unpacking jsonschema==2.5.1 03:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:16:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:16: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:16: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:16: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:16: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:16:53 INFO - Downloading functools32-3.2.3-2.tar.gz 03:16:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:16:53 INFO - warning: no files found matching '*.txt' 03:16:53 INFO - no previously-included directories found matching 'build' 03:16:53 INFO - no previously-included directories found matching 'dist' 03:16:53 INFO - no previously-included directories found matching '.git*' 03:16:53 INFO - Installing collected packages: jsonschema, functools32 03:16:53 INFO - Running setup.py install for functools32 03:16:53 INFO - warning: no files found matching '*.txt' 03:16:53 INFO - no previously-included directories found matching 'build' 03:16:53 INFO - no previously-included directories found matching 'dist' 03:16:53 INFO - no previously-included directories found matching '.git*' 03:16:54 INFO - Successfully installed jsonschema functools32 03:16:54 INFO - Cleaning up... 03:16:54 INFO - Return code: 0 03:16:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1fe31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21aca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x234fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2353090>, '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 0x2353d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x234df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', '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:16:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:16:54 INFO - Using env: {'DISPLAY': ':0', 03:16:54 INFO - 'HOME': '/home/cltbld', 03:16:54 INFO - 'LANG': 'en_US.UTF-8', 03:16:54 INFO - 'LANGUAGE': 'en_US:en', 03:16:54 INFO - 'LOGNAME': 'cltbld', 03:16:54 INFO - 'MAIL': '/var/mail/cltbld', 03:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:54 INFO - 'MOZ_NO_REMOTE': '1', 03:16:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:54 INFO - 'NO_EM_RESTART': '1', 03:16:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:54 INFO - 'PWD': '/builds/slave/test', 03:16:54 INFO - 'SHELL': '/bin/bash', 03:16:54 INFO - 'SHLVL': '1', 03:16:54 INFO - 'TERM': 'linux', 03:16:54 INFO - 'TMOUT': '86400', 03:16:54 INFO - 'USER': 'cltbld', 03:16:54 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', 03:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:54 INFO - '_': '/tools/buildbot/bin/python'} 03:16:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:16:54 INFO - Cleaning up... 03:16:54 INFO - Return code: 0 03:16:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1fe31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21aca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x234fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2353090>, '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 0x2353d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x234df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', '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:16:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:16:54 INFO - Using env: {'DISPLAY': ':0', 03:16:54 INFO - 'HOME': '/home/cltbld', 03:16:54 INFO - 'LANG': 'en_US.UTF-8', 03:16:54 INFO - 'LANGUAGE': 'en_US:en', 03:16:54 INFO - 'LOGNAME': 'cltbld', 03:16:54 INFO - 'MAIL': '/var/mail/cltbld', 03:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:54 INFO - 'MOZ_NO_REMOTE': '1', 03:16:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:54 INFO - 'NO_EM_RESTART': '1', 03:16:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:54 INFO - 'PWD': '/builds/slave/test', 03:16:54 INFO - 'SHELL': '/bin/bash', 03:16:54 INFO - 'SHLVL': '1', 03:16:54 INFO - 'TERM': 'linux', 03:16:54 INFO - 'TMOUT': '86400', 03:16:54 INFO - 'USER': 'cltbld', 03:16:54 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', 03:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:54 INFO - '_': '/tools/buildbot/bin/python'} 03:16:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:54 INFO - Downloading/unpacking blobuploader==1.2.4 03:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:55 INFO - Downloading blobuploader-1.2.4.tar.gz 03:16:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:16:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:16:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:56 INFO - Downloading docopt-0.6.1.tar.gz 03:16:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:16:56 INFO - Installing collected packages: blobuploader, requests, docopt 03:16:56 INFO - Running setup.py install for blobuploader 03:16:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:16:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:16:56 INFO - Running setup.py install for requests 03:16:56 INFO - Running setup.py install for docopt 03:16:56 INFO - Successfully installed blobuploader requests docopt 03:16:56 INFO - Cleaning up... 03:16:56 INFO - Return code: 0 03:16:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21aca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x234fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2353090>, '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 0x2353d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x234df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', '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:16:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:16:56 INFO - Using env: {'DISPLAY': ':0', 03:16:56 INFO - 'HOME': '/home/cltbld', 03:16:56 INFO - 'LANG': 'en_US.UTF-8', 03:16:56 INFO - 'LANGUAGE': 'en_US:en', 03:16:56 INFO - 'LOGNAME': 'cltbld', 03:16:56 INFO - 'MAIL': '/var/mail/cltbld', 03:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:56 INFO - 'MOZ_NO_REMOTE': '1', 03:16:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:56 INFO - 'NO_EM_RESTART': '1', 03:16:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:56 INFO - 'PWD': '/builds/slave/test', 03:16:56 INFO - 'SHELL': '/bin/bash', 03:16:56 INFO - 'SHLVL': '1', 03:16:56 INFO - 'TERM': 'linux', 03:16:56 INFO - 'TMOUT': '86400', 03:16:56 INFO - 'USER': 'cltbld', 03:16:56 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', 03:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:56 INFO - '_': '/tools/buildbot/bin/python'} 03:16:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:16:57 INFO - Running setup.py (path:/tmp/pip-HifqzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:16:57 INFO - Running setup.py (path:/tmp/pip-5pht1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:16:57 INFO - Running setup.py (path:/tmp/pip-xQk3oM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:16:57 INFO - Running setup.py (path:/tmp/pip-4bsvzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:16:57 INFO - Running setup.py (path:/tmp/pip-Cm01AX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:16:57 INFO - Running setup.py (path:/tmp/pip-PJVRUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:16:57 INFO - Running setup.py (path:/tmp/pip-2sfn08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:16:57 INFO - Running setup.py (path:/tmp/pip-sW6ft9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:16:57 INFO - Running setup.py (path:/tmp/pip-xBPlcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:16:57 INFO - Running setup.py (path:/tmp/pip-AgsBah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:16:57 INFO - Running setup.py (path:/tmp/pip-FoKB8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:16:57 INFO - Running setup.py (path:/tmp/pip-yT3XT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:16:57 INFO - Running setup.py (path:/tmp/pip-PJ41_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:16:57 INFO - Running setup.py (path:/tmp/pip-qR602o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:16:58 INFO - Running setup.py (path:/tmp/pip-DFHB3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:16:58 INFO - Running setup.py (path:/tmp/pip-EAyD99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:16:58 INFO - Running setup.py (path:/tmp/pip-dskEL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:16:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:16:58 INFO - Running setup.py install for manifestparser 03:16:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:16:58 INFO - Running setup.py install for mozcrash 03:16:58 INFO - Running setup.py install for mozdebug 03:16:58 INFO - Running setup.py install for mozdevice 03:16:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:16:58 INFO - Running setup.py install for mozfile 03:16:58 INFO - Running setup.py install for mozhttpd 03:16:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:16:58 INFO - Running setup.py install for mozinfo 03:16:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:16:58 INFO - Running setup.py install for mozInstall 03:16:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:16:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:16:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:16:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:16:59 INFO - Running setup.py install for mozleak 03:16:59 INFO - Running setup.py install for mozlog 03:16:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:16:59 INFO - Running setup.py install for moznetwork 03:16:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:16:59 INFO - Running setup.py install for mozprocess 03:16:59 INFO - Running setup.py install for mozprofile 03:16:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:16:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:16:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:16:59 INFO - Running setup.py install for mozrunner 03:16:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:16:59 INFO - Running setup.py install for mozscreenshot 03:16:59 INFO - Running setup.py install for moztest 03:16:59 INFO - Running setup.py install for mozversion 03:16:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:16:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:16:59 INFO - Cleaning up... 03:16:59 INFO - Return code: 0 03:16:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:17:00 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:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:00 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:17:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:00 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 0x1fe31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21aca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x234fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2353090>, '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 0x2353d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x234df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', '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:17:00 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:17:00 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:17:00 INFO - Using env: {'DISPLAY': ':0', 03:17:00 INFO - 'HOME': '/home/cltbld', 03:17:00 INFO - 'LANG': 'en_US.UTF-8', 03:17:00 INFO - 'LANGUAGE': 'en_US:en', 03:17:00 INFO - 'LOGNAME': 'cltbld', 03:17:00 INFO - 'MAIL': '/var/mail/cltbld', 03:17:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:00 INFO - 'MOZ_NO_REMOTE': '1', 03:17:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:00 INFO - 'NO_EM_RESTART': '1', 03:17:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:00 INFO - 'PWD': '/builds/slave/test', 03:17:00 INFO - 'SHELL': '/bin/bash', 03:17:00 INFO - 'SHLVL': '1', 03:17:00 INFO - 'TERM': 'linux', 03:17:00 INFO - 'TMOUT': '86400', 03:17:00 INFO - 'USER': 'cltbld', 03:17:00 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', 03:17:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:00 INFO - '_': '/tools/buildbot/bin/python'} 03:17:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:17:00 INFO - Running setup.py (path:/tmp/pip-jLIPPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:17:00 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:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:17:00 INFO - Running setup.py (path:/tmp/pip-EnklDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:17:00 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:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:17:00 INFO - Running setup.py (path:/tmp/pip-yTthzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:17:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:17:00 INFO - Running setup.py (path:/tmp/pip-Jl_Cbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:17:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:17:00 INFO - Running setup.py (path:/tmp/pip-NnU7te-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:17:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:17:00 INFO - Running setup.py (path:/tmp/pip-TeRkn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:17:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:17:00 INFO - Running setup.py (path:/tmp/pip-ns9WbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:17:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:17:00 INFO - Running setup.py (path:/tmp/pip-5dLLJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:17:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:17:00 INFO - Running setup.py (path:/tmp/pip-7TJEIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:17:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:17:00 INFO - Running setup.py (path:/tmp/pip-HHT9Dx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:17:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:17:00 INFO - Running setup.py (path:/tmp/pip-TX32AC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:17:01 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:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:17:01 INFO - Running setup.py (path:/tmp/pip-5d3NrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:17:01 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:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:17:01 INFO - Running setup.py (path:/tmp/pip-oau7rK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:17:01 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:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:17:01 INFO - Running setup.py (path:/tmp/pip-RIKvec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:17:01 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:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:17:01 INFO - Running setup.py (path:/tmp/pip-yhl15y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:17:01 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:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:17:01 INFO - Running setup.py (path:/tmp/pip-1At3a8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:17:01 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:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:17:01 INFO - Running setup.py (path:/tmp/pip-xPKc3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:17:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:17:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:17:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:17:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:17:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:17:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:17:01 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:17:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:02 INFO - Downloading blessings-1.6.tar.gz 03:17:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:17:02 INFO - Installing collected packages: blessings 03:17:02 INFO - Running setup.py install for blessings 03:17:02 INFO - Successfully installed blessings 03:17:02 INFO - Cleaning up... 03:17:02 INFO - Return code: 0 03:17:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:17:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:17:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:17:02 INFO - Reading from file tmpfile_stdout 03:17:02 INFO - Current package versions: 03:17:02 INFO - argparse == 1.2.1 03:17:02 INFO - blessings == 1.6 03:17:02 INFO - blobuploader == 1.2.4 03:17:02 INFO - docopt == 0.6.1 03:17:02 INFO - functools32 == 3.2.3-2 03:17:02 INFO - jsonschema == 2.5.1 03:17:02 INFO - manifestparser == 1.1 03:17:02 INFO - mozInstall == 1.13 03:17:02 INFO - mozcrash == 1.0 03:17:02 INFO - mozdebug == 0.1 03:17:02 INFO - mozdevice == 0.51 03:17:02 INFO - mozfile == 1.2 03:17:02 INFO - mozhttpd == 0.7 03:17:02 INFO - mozinfo == 0.10 03:17:02 INFO - mozleak == 0.1 03:17:02 INFO - mozlog == 3.5 03:17:02 INFO - moznetwork == 0.27 03:17:02 INFO - mozprocess == 0.25 03:17:02 INFO - mozprofile == 0.28 03:17:02 INFO - mozrunner == 6.13 03:17:02 INFO - mozscreenshot == 0.1 03:17:02 INFO - mozsystemmonitor == 0.3 03:17:02 INFO - moztest == 0.8 03:17:02 INFO - mozversion == 1.4 03:17:02 INFO - psutil == 3.1.1 03:17:02 INFO - requests == 1.2.3 03:17:02 INFO - wsgiref == 0.1.2 03:17:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21aca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x234fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2353090>, '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 0x2353d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x234df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', '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:17:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:17:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:17:02 INFO - Using env: {'DISPLAY': ':0', 03:17:02 INFO - 'HOME': '/home/cltbld', 03:17:02 INFO - 'LANG': 'en_US.UTF-8', 03:17:02 INFO - 'LANGUAGE': 'en_US:en', 03:17:02 INFO - 'LOGNAME': 'cltbld', 03:17:02 INFO - 'MAIL': '/var/mail/cltbld', 03:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:02 INFO - 'MOZ_NO_REMOTE': '1', 03:17:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:02 INFO - 'NO_EM_RESTART': '1', 03:17:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:02 INFO - 'PWD': '/builds/slave/test', 03:17:02 INFO - 'SHELL': '/bin/bash', 03:17:02 INFO - 'SHLVL': '1', 03:17:02 INFO - 'TERM': 'linux', 03:17:02 INFO - 'TMOUT': '86400', 03:17:02 INFO - 'USER': 'cltbld', 03:17:02 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', 03:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:02 INFO - '_': '/tools/buildbot/bin/python'} 03:17:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:03 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:17:03 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:17:03 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:17:03 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:17:03 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:17:03 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:17:03 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:17:03 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:17:03 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:17:03 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:17:04 INFO - Installing collected packages: simplejson 03:17:04 INFO - Running setup.py install for simplejson 03:17:04 INFO - building 'simplejson._speedups' extension 03:17:04 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 03:17:05 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:17:05 INFO - Successfully installed simplejson 03:17:05 INFO - Cleaning up... 03:17:05 INFO - Return code: 0 03:17:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21aca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x234fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2353090>, '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 0x2353d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x234df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', '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:17:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:17:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:17:05 INFO - Using env: {'DISPLAY': ':0', 03:17:05 INFO - 'HOME': '/home/cltbld', 03:17:05 INFO - 'LANG': 'en_US.UTF-8', 03:17:05 INFO - 'LANGUAGE': 'en_US:en', 03:17:05 INFO - 'LOGNAME': 'cltbld', 03:17:05 INFO - 'MAIL': '/var/mail/cltbld', 03:17:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:05 INFO - 'MOZ_NO_REMOTE': '1', 03:17:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:05 INFO - 'NO_EM_RESTART': '1', 03:17:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:05 INFO - 'PWD': '/builds/slave/test', 03:17:05 INFO - 'SHELL': '/bin/bash', 03:17:05 INFO - 'SHLVL': '1', 03:17:05 INFO - 'TERM': 'linux', 03:17:05 INFO - 'TMOUT': '86400', 03:17:05 INFO - 'USER': 'cltbld', 03:17:05 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', 03:17:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:05 INFO - '_': '/tools/buildbot/bin/python'} 03:17:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:17:05 INFO - Cleaning up... 03:17:05 INFO - Return code: 0 03:17:05 INFO - Running post-action listener: _resource_record_post_action 03:17:05 INFO - Running post-action listener: _start_resource_monitoring 03:17:05 INFO - Starting resource monitoring. 03:17:05 INFO - [mozharness: 2017-09-04 10:17:05.664496Z] Finished create-virtualenv step (success) 03:17:05 INFO - [mozharness: 2017-09-04 10:17:05.664957Z] Running install step. 03:17:05 INFO - Running pre-action listener: _resource_record_pre_action 03:17:05 INFO - Running main action method: install 03:17:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:17:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:17:05 INFO - Reading from file tmpfile_stdout 03:17:05 INFO - Detecting whether we're running mozinstall >=1.0... 03:17:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:17:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:17:06 INFO - Reading from file tmpfile_stdout 03:17:06 INFO - Output received: 03:17:06 INFO - Usage: mozinstall [options] installer 03:17:06 INFO - Options: 03:17:06 INFO - -h, --help show this help message and exit 03:17:06 INFO - -d DEST, --destination=DEST 03:17:06 INFO - Directory to install application into. [default: 03:17:06 INFO - "/builds/slave/test"] 03:17:06 INFO - --app=APP Application being installed. [default: firefox] 03:17:06 INFO - mkdir: /builds/slave/test/build/application 03:17:06 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:17:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:17:22 INFO - Reading from file tmpfile_stdout 03:17:22 INFO - Output received: 03:17:22 INFO - /builds/slave/test/build/application/firefox/firefox 03:17:22 INFO - Running post-action listener: _resource_record_post_action 03:17:22 INFO - [mozharness: 2017-09-04 10:17:22.555770Z] Finished install step (success) 03:17:22 INFO - [mozharness: 2017-09-04 10:17:22.556020Z] Running setup-mitmproxy step. 03:17:22 INFO - Running pre-action listener: _resource_record_pre_action 03:17:22 INFO - Running main action method: setup_mitmproxy 03:17:22 INFO - Skipping: mitmproxy is not required 03:17:22 INFO - Running post-action listener: _resource_record_post_action 03:17:22 INFO - [mozharness: 2017-09-04 10:17:22.557118Z] Finished setup-mitmproxy step (success) 03:17:22 INFO - [mozharness: 2017-09-04 10:17:22.557333Z] Running run-tests step. 03:17:22 INFO - Running pre-action listener: _resource_record_pre_action 03:17:22 INFO - Running pre-action listener: _set_gcov_prefix 03:17:22 INFO - Running main action method: run_tests 03:17:22 WARNING - Try message not found. 03:17:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:17:22 INFO - Python 2.7.3 03:17:22 INFO - Return code: 0 03:17:22 INFO - grabbing minidump binary from tooltool 03:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:22 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 0x2353090>, '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 0x2353d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x234df90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:17:22 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:17:22 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:17:22 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:17:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:17:22 INFO - Return code: 0 03:17:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:17:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:17:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:17:22 INFO - ENV: RUST_BACKTRACE is now full 03:17:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:17:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:17:22 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/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:17:22 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/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-058 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:17:22 INFO - Using env: {'DISPLAY': ':0', 03:17:22 INFO - 'HOME': '/home/cltbld', 03:17:22 INFO - 'LANG': 'en_US.UTF-8', 03:17:22 INFO - 'LANGUAGE': 'en_US:en', 03:17:22 INFO - 'LOGNAME': 'cltbld', 03:17:22 INFO - 'MAIL': '/var/mail/cltbld', 03:17:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:17:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:17:22 INFO - 'MOZ_ACCELERATED': '1', 03:17:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:22 INFO - 'MOZ_NO_REMOTE': '1', 03:17:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:17:22 INFO - 'MOZ_WEBRENDER': '1', 03:17:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:22 INFO - 'NO_EM_RESTART': '1', 03:17:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:22 INFO - 'PWD': '/builds/slave/test', 03:17:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:17:22 INFO - 'RUST_BACKTRACE': 'full', 03:17:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:17:22 INFO - 'SHELL': '/bin/bash', 03:17:22 INFO - 'SHLVL': '1', 03:17:22 INFO - 'TERM': 'linux', 03:17:22 INFO - 'TMOUT': '86400', 03:17:22 INFO - 'USER': 'cltbld', 03:17:22 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', 03:17:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:22 INFO - '_': '/tools/buildbot/bin/python'} 03:17:22 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/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:17:22 INFO - mozversion application_buildid: 20170904091258 03:17:22 INFO - mozversion application_changeset: 632e42dca494ec3d90b70325d9c359f80cb3f38a 03:17:22 INFO - mozversion application_display_name: Nightly 03:17:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:17:22 INFO - mozversion application_name: Firefox 03:17:22 INFO - mozversion application_remotingname: firefox 03:17:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:17:22 INFO - mozversion application_vendor: Mozilla 03:17:22 INFO - mozversion application_version: 57.0a1 03:17:22 INFO - mozversion platform_buildid: 20170904091258 03:17:22 INFO - mozversion platform_changeset: 632e42dca494ec3d90b70325d9c359f80cb3f38a 03:17:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:17:22 INFO - mozversion platform_version: 57.0a1 03:17:22 INFO - using testdate: 1504520242 03:17:22 INFO - actual date: 1504520242 03:17:22 INFO - starting webserver on 'localhost:33223' 03:17:22 INFO - SUITE-START | Running 2 tests 03:17:22 INFO - TEST-START | dromaeo_css 03:17:22 INFO - Initialising browser for dromaeo_css test... 03:17:22 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33223/getInfo.html -profile /tmp/tmpU1o0CX/profile 03:17:22 INFO - TEST-INFO | started process 6486 (/builds/slave/test/build/application/firefox/firefox http://localhost:33223/getInfo.html) 03:17:30 INFO - TEST-INFO | 6486: exit 0 03:17:30 INFO - Browser initialized. 03:17:30 INFO - Running cycle 1/1 for dromaeo_css test... 03:17:30 INFO - Using env: {'DISPLAY': ':0', 03:17:30 INFO - 'HOME': '/home/cltbld', 03:17:30 INFO - 'JSGC_DISABLE_POISONING': '1', 03:17:30 INFO - 'LANG': 'en_US.UTF-8', 03:17:30 INFO - 'LANGUAGE': 'en_US:en', 03:17:30 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:17:30 INFO - 'LOGNAME': 'cltbld', 03:17:30 INFO - 'MAIL': '/var/mail/cltbld', 03:17:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:17:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:17:30 INFO - 'MOZ_ACCELERATED': '1', 03:17:30 INFO - 'MOZ_CRASHREPORTER': '1', 03:17:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:30 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:17:30 INFO - 'MOZ_NO_REMOTE': '1', 03:17:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:17:30 INFO - 'MOZ_WEBRENDER': '1', 03:17:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:30 INFO - 'NO_EM_RESTART': '1', 03:17:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:30 INFO - 'PWD': '/builds/slave/test', 03:17:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:17:30 INFO - 'RUST_BACKTRACE': 'full', 03:17:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:17:30 INFO - 'SHELL': '/bin/bash', 03:17:30 INFO - 'SHLVL': '1', 03:17:30 INFO - 'TERM': 'linux', 03:17:30 INFO - 'TMOUT': '86400', 03:17:30 INFO - 'USER': 'cltbld', 03:17:30 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', 03:17:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:30 INFO - '_': '/tools/buildbot/bin/python'} 03:17:30 INFO - TEST-INFO | started process 6681 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpU1o0CX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:17:31 INFO - PID 6681 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:17:31 INFO - PID 6681 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:17:31 INFO - PID 6681 | 03:17:31 INFO - PID 6681 | (/builds/slave/test/build/application/firefox/firefox:6744): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:17:31 INFO - PID 6681 | 03:17:41 INFO - PID 6681 | RSS: Main: 207228928 03:17:41 INFO - PID 6681 | 03:17:45 INFO - PID 6681 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:20:41 INFO - PID 6681 | Cycle 1(1): loaded http://localhost:33223/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33223/tests/dromaeo/cssquery-ext.html) 03:20:43 INFO - PID 6681 | RSS: Main: 218390528 03:20:43 INFO - PID 6681 | 03:23:45 INFO - PID 6681 | Cycle 1(1): loaded http://localhost:33223/tests/dromaeo/cssquery-ext.html (next: http://localhost:33223/tests/dromaeo/cssquery-jquery.html) 03:23:45 INFO - PID 6681 | RSS: Main: 220844032 03:23:45 INFO - PID 6681 | 03:26:47 INFO - PID 6681 | Cycle 1(1): loaded http://localhost:33223/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33223/tests/dromaeo/cssquery-mootools.html) 03:26:48 INFO - PID 6681 | RSS: Main: 224075776 03:26:48 INFO - PID 6681 | 03:29:50 INFO - PID 6681 | Cycle 1(1): loaded http://localhost:33223/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33223/tests/dromaeo/cssquery-prototype.html) 03:29:51 INFO - PID 6681 | RSS: Main: 219185152 03:29:51 INFO - PID 6681 | 03:32:55 INFO - PID 6681 | Cycle 1(1): loaded http://localhost:33223/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33223/tests/dromaeo/cssquery-yui.html) 03:32:55 INFO - PID 6681 | RSS: Main: 221048832 03:32:55 INFO - PID 6681 | 03:35:57 INFO - PID 6681 | Cycle 1(1): loaded http://localhost:33223/tests/dromaeo/cssquery-yui.html (next: http://localhost:33223/tests/dromaeo/cssquery-dojo.html) 03:35:57 INFO - PID 6681 | RSS: Main: 224104448 03:35:57 INFO - PID 6681 | 03:35:57 INFO - PID 6681 | __start_tp_report 03:35:57 INFO - PID 6681 | _x_x_mozilla_page_load 03:35:57 INFO - PID 6681 | _x_x_mozilla_page_load_details 03:35:57 INFO - PID 6681 | |i|pagename|runs| 03:35:57 INFO - PID 6681 | |0;dojo.html;3331.2005118771485;3333.783304085235;3369.5956485221777;3402.5406570113028;3407.965920340796;6454.451371633405;6492.156019717436;6531.999999999994;6545.018247262901;6556.606603603787;6226.564140510166;6347.49220062395;6356.269029061659;6382.585131966419;6412.967935160318;5813.418658134195;5861.589688721791;5988.940110598882;6020.217371741689;6039.214902062748;5912.054071348585;5968.7612495500125;6002.759889604411;6010.098485227207;6025.849353766147;5739.261700269813;5740.655560666352;5838.824835254928;5862.38444963277;5997.910031349533;5951.166836642873;5952.255968003999;5976.252968378953;6015.0676645120075;6028.547858910555;5783.9710801446145;5817.592768506206;5842.503387212092;5961.701914904237;5995.700214989233;6231.096491008823;6315.24217093947;6322.114903913456;6344.904826427607;6360.045993101072;6773.1872175338785;6843.62360070196;6856.074429951943;6864.691088901011;6954.374106330454;6937.895931561031;7057.788266352018;7077;7078.292170782932;7090.326418990187;6403.135576697133;6422.325655806119;6439.7746078887485;6526.184226971629;6583.506237032146;6958.860822783516;6972.546784458994;6975.79072627822;6995.8950615740805;7264.418846492268;6696.86606267872;6757.087793147961;6764.154480689914;6768.864622707519;6780.864382712318;6859.588424694534;6871.03805467235;6876.484263680244;6905.102336696197;6914.723411063602;6800.31996800316;6823.624700641512;6841.350071743176;6848.1097457331225;6898.793036208922;6863.073485079551;6868.072810170563;6909.654517274116;6938.41023512997;7018.824529386806;5348.705821179872;5430.456954304538;5435.619506634498;5442.265294185314;5536.363318218376;4657.208274593328;4699.318598803223;4756.096341695067;4858.02839432115;4858.17411040124;5947.286325640951;5986.6707331097205;6042.758289668452;6047.758089676364;6071.210742603434;6713.69788359525;6732.966335168293;6777.051212830208;6785.42323902464;6806.523543351917;5845.707714614252;5930.911036334458;5939.2575928009;5947.405259474018;5954.970225148933;5015.899682006339;5810.360860305363;5817.301923769175;5840.445157710011;5922.111683247547;6642.269350371455;6689.665516724242;6731.798046058626;6740.4270636995425;6786.932130678729;6701.296363881917;6742.157230346207;6833.794986150423;6880.277570854988;6975.267596902252;6596.637184954873;6778.525503214728;6818.659067046727;6850.554713943578;6852.5203235773015;6781.186257649114;6796;6831.863362732817;6912.619805910522;6945.340192681687;6906.205786334636;6978.441724661913;6983.2318444972025;6996.930030699831;7052.9647351762915;6663.8000859972335;6663.833404164935;6666.600003999775;6671.166104236971;6682.398584127452;6693.464522838259;6747.325267473213;6752.257251702407;6811;6812.965935170292;6526.9020964686515;6604.636744979072;6623.768168114093;6691.498137639599;6729.226138993882;6608.173978252718;6728.3271672832325;6811.489138314547;6814.114165158498;6875.346842049998;6299.527535434769;6383.553151279366;6407.551471396958;6418.614883107029;6427.260865000398 03:35:57 INFO - PID 6681 | |1;ext.html;783.5416281475336;791.8693415586429;813.357447616383;841.2933136165622;842.5071333270033;40835.59164408355;41106.794466027706;41371.58628413715;41481.58518414815;41592.79203603981;37360.626393736056;37647.81176094116;37740.24519509608;37851.43222851659;37858.621413785855;6820.11338525991;6875.5530890491955;6945.201301850276;6948.930510694904;6961.338672826098;6847.965760171217;6898.862022759542;6903.309669033081;6910.378065974061;6911.308869113099;6833.214180369271;6873.243943166248;7718.729844455417;7903.012123484565;7957.442978991444;7825.726099586515;7872.094709108468;7884.172161923001;7898.763037108896;7943.5631040292765;7708.0750309962605;7777.377809775261;7781.610919454061;7787.338076263524;7790.220977902221;38380.42429363562;39323.41014884779;39344.21311573781;39528.80235598832;39643.60356396428;3443.362977849106;3505.4917037029486;3528.0826984984087;3531.1172206948263;3534.9469757953652;31918.680813191804;31935.520967185512;31941.84029079863;31955.520667190012;32081.358372832412;5083.669561478529;5118.104331741967;5132.717700526469;5154.278401023859;5192.221166824968;181175;182257;183929.0803545987;184019;185047;47380.52619473761;47724.284135737995;47834.28248576274;48044.519554804705;48188.27717584239;49804.75097624489;49912.75043624759;50125.74937125291;50240;50333.24500132501;15344.386224550894;15607.921960390126;15654.217289135499;15715.135667538396;15772;12868.099233053596;12892.097553171376;12894.097413180987;12918.547850825176;12954.611361659165;13994.650133746534;14077.225752583501;14119.858801411854;14170.291485425894;14235.715285694228;5439.864003399867;5524.14375771756;5594.412586678422;5714.457096583982;5755.280589926259;981.2003217377815;984.7538115471132;989.6734077754323;1014.6499457687086;1015.304516406264;22154.778452215593;22376.44058898508;22404.10383584671;22411.43971400728;22449.32652020442;1095.315427857589;1109.3565731875492;1112.3881864974394;1116.547798141754;1116.843641890136;11831.585894493823;11871.406429678482;12027;12106.81839772404;12111.212771169845;221401;223133;223191;223350;223411;45284.77357613257;45961;50231.74884125556;51103.48896511061;51587.742061290206;1443.8483959184134;1445.2773613193403;1453.062774510456;1456.8178977628008;1460.0654133273456;26699.33251668724;27009;27231.319217019733;27572.310692232855;27861.303467413476;36914.63085369112;36936.26127477382;36955.26089478249;37050.258994819415;37059.62940370562;188765;188888;191074;191153;191435.21411964257;33322.666773331955;34713.65286347104;34810;34842.30315393627;35034;41985.58014419817;42347.3647895288;42563.7230883074;42613;42974.570254298305;30692.386152277275;30956.84521577468;31051.068467946;31208;31279.84360078185;31553.36893262076;31942.68057319397;33160.668393315755;34431.31137377188;34672.47991280082;38301.42547861728;38602.42096368501;39150.412743808294;39472.802635986634;39511.407328889516;33271.16822079467;33515;34016.319673606886;34029;34081.14797130092;23583.646245306678;24273.757262427855;24363.51272974495;24398.390040249134;24795.25614231576 03:35:57 INFO - PID 6681 | |2;jquery.html;572.0493118466031;582.6591444005256;589.1075021342664;593.5103539579842;600.2916558461015;22079.227227047057;22568.66147007796;22746.31761047167;22857.771422285772;23032.078716851327;24145.275641730732;25086.121985730504;26509.734902651016;26629.73370266297;26658.33354166147;7167.784966451002;7323.084614423197;7343.302386273295;7352.77941661751;7376.188619251905;7233.421326293884;7248.855022899539;7253.963730181342;7318.817029574277;7326.523775954546;4313.698041137121;4313.913721725564;4320.287152619814;5469.617126801126;7131.607761573111;6978.965105174441;7079.89380159298;7083.397911177556;7095.04216930718;7120.537165084279;6907.274736152682;6917.550359226684;6924.446044316443;6958.5824850508625;6958.65206739661;29134.417311653648;29815.850920745474;30397.69602303971;31142;31219.063428096968;2079.5009197792574;2104.3371338028473;2110.609537235601;2115.164510018534;2118.459792752838;20541.281055163;21092.578148436944;21097.8945105275;21237.25669601572;21255.787442125536;2548.1718441506587;2559.3473664215694;2649.3244222723274;2661.640678508396;2667.199840047978;140681.29659351637;143670;144105;145456.54543454535;146003;19834.719007314055;21006.47483812917;21049.684254736192;21881.234156804436;23276;41487.37768933469;41627;41693;41715.37426938598;41823.7908810454;9721.513924303756;9920.801583968425;10149.34029288094;10162.288639795144;10326.793464130826;9337.393069450463;9401.482918439551;9439.528023598792;9440.29197810167;9485.193758530602;8778.561071946506;8901.243394311427;9070.365074444857;9096.1813436791;9562.330636855493;2764.5714914188334;2769.529180039398;2804.6213761141985;2836.673782515037;2851.2871782054485;958.3770549143113;975.9804803903883;981.0777868803302;983.7737320416201;987.7530617345664;19891.701624475645;20004.19983200685;20031.799682003286;20502.282420115225;20780.688289675665;1044.2585764107416;1048.192891473561;1054.1882750282393;1055.5144633468537;1056;11126.92315347411;11197.776044479066;11215.158863085138;11278.774424511465;11372.829407558893;415000;429198;430153;443002;443190;105940;109659.45170274258;109915;110635.44682276537;110762;1187.9762404751857;1205.0660737928176;1205.4093494187784;1220.725336799213;1221.7617564575;17594.384196553572;17911.19399627046;17949.282028718706;18087;18363.54091147732;52754.73622631862;54198.45801541934;55835;56073.15890261568;57245.71377143088;69951.60096798137;70503.64748176227;70949.64525177341;71090;71806.28193717996;14270;14748.63128421798;14751.114933104049;14802.111873287637;14812.111273323635;21932.342029739135;22932.968016439634;23085.769142308363;23315.183968561018;23367;17733;18330.175142118904;18605.348812791486;18669.626607467504;18714.251429943193;16177.191140443167;16178.191090445665;16218.35126594923;16472.917635412225;16514.83485165181;18778.15498302607;18785.34251301198;18816.059197040366;18914.905425472785;19045.71431428502;13870.791938120734;14370.13779173253;14653.487127950471;14737.336819842918;14802.185879776285;15972.760408593647;16129.596760081093;18311.542211444288;18767.71848422302;18856 03:35:57 INFO - PID 6681 | |3;mootools.html;1304.2239867278968;1337.6722702937782;1369.2263870912927;1377.4627895120898;1378.4279523997545;15159;15760.527184184479;15789.763153552705;15799.210039498008;15810.762838557403;15148.091114533136;15964.28160732767;16140.83859161408;16302.673946521063;16334.754978675299;2795.4828356754015;2826.9151925442166;2852.4580329737387;2854.0866922584783;2857.3285277959662;2718.2660681615953;2777.4167424840807;2788.665360156784;2826.6042754014456;2842.8862845486155;2735.644366232387;2838.5032619291646;2841.801073924826;2844.3315820782204;2850.330172409482;2604.414006848463;2605.257501612041;2631.368471566829;2634.802389820752;2637.274749443899;2810.8594570271607;2825.3925406037597;2832.7592154666877;2845.601615773793;2849.0455697341417;15075.09549427038;15214.16322102284;15434.228288585526;15434.691306173814;15459;8128.9593552031865;8382.706605268786;8418.452800568004;8440.451370660949;8449.239568438808;5128.384593848725;5288.206768984644;5330.093884039723;5354.651947623392;5462.508374246337;1876.8217019383137;1909.3508207209616;1918.472420084474;1940.0978544976513;1978.8021197880241;26482.073127440446;26484;26515.469690606078;26591.335216619547;26652.33369165767;6568.704408301638;6752.898706519406;6826.761063362758;6924.23833378328;6927.203371612229;16208.865379423563;16216.270267837976;16217.432389866299;16246.675066498603;16303.91848040776;7917.208279172152;8016.198380161937;8085.110637829834;8107.635156417975;8192.918070819216;7077.7168913242895;7360;7505;7533.4349923755935;7658.65536050879;5137.588992880561;5214.843554693365;5323.3611966563485;5342.866428339323;5370.409254981949;1413.0673755321643;1472.7201831651953;1558.7428074367829;1560.1653115583335;1560.84391560843;747.5925620536821;765.1545042727817;765.7434759355567;787.1498781316164;787.4684587903142;8286.668533258602;8701.738947831574;8730.17063378978;8800.603972821238;8830.602622881986;975.9853602195973;992;1000.7398076500162;1005.1205195453978;1008.8133695266399;5108.259302401131;5136.614753893473;5328.973355133199;5362.892742145135;5500.669959802424;21653.78346216549;22268.777312226994;22306.21928232504;22388.77611223867;22606.660900086183;20874.582508349748;21991.340259792232;22520.54958900813;22530.774692252868;22543.549129017327;10734.194935379877;10825.458727063773;10913.672589822318;10989.505472253768;11014.44927753609;8807.383483156118;9281.767955801159;9335.346525743404;9407.106324899123;9437.858432123387;18631.254749809857;18804.247830086642;19264.518387040436;19311.227550897805;19587.118579663664;19484;19650.803491964896;20059.89970050199;20299.391018269474;20647.17411303531;11537.250078744855;11583.478743456397;11683.18217724751;11803.586874459696;11830;13688.657783555092;13885.791713124108;13927.58217253484;13973.161610303414;14014.859851401356;11189.496472658913;11213.102951764002;11298.322100673986;11555.133364997828;11790.469428875549;10214.795704085727;10397.168226541708;10481.895181048092;10537.683869483928;10598.523066462181;9554.378965367228;9668.806623867624;9931.95034024854;9946.950265248628;9952.303338766507;7158.713651453883;7186.60473673932;7231.710731570678;7252.891206631799;7288.453365997465;7057.929420705933;7065.187503437171;7075.858482830417;7119.82200444993;7181.569105853666 03:35:57 INFO - PID 6681 | |4;prototype.html;494.68660705826034;498.6735284144177;527.1881302793697;532.5952276270034;533.0485084124836;6324.367563243673;6381;6390.329015453381;6401.9999999999945;6480.7731729389425;6120.602160859541;6225.7509699611965;6282.874342513159;6309.936900630993;6348.428641422271;5013.298138260647;5054.823081192159;5112.795488180468;5114.28400023997;5148.227765835127;4943.9347604485265;5027.09512287788;5040.747962601855;5051.191809310511;5096.872578185538;4901.68139070961;4987.975060124694;4998.625103117262;5049.873753156182;5065;4986.177280748672;5012.323336349601;5069.2142717878605;5108.64239503235;5110.156824124052;4497.595216430537;4518.841840535599;4522.140793249305;4535.319702044687;4537.591616754475;6158.261008678943;6167.444929956326;6208.441240288351;6283.780067697654;6298.338674439164;5763.060838972113;5806.361300256968;5809.2447981762525;5840.707964601753;5867.647941123546;6728.663566821689;6731.528792984493;6736.157980252468;6740.460763138937;6787.2534021257625;4850.95149048512;4896.265560165968;4905.4358748744;4923.08922849274;4924.359833221694;6922.169339679221;7000.369966703021;7029.207552074239;7114.359707626339;7120.216776154619;5063.11395505785;5090.389153301628;5096.108181068298;5107.544349860346;5126.231065340155;5135.281060651511;5161.329027226511;5169.353830771153;5189;5203.479652034766;5756.481916627524;5785.855353616109;5790.334111577138;5804.4195580442465;5819.679917604488;6598.47212223021;6668.199816022108;6725.159355080615;6727.501699116432;6751.358620931003;1760.7534945107695;1799.073477159264;1799.1723807048643;1806.2594336322045;1807.313220976047;1142.662914440242;1157.2593540134321;1167.445463404893;1191.82605133943;1197.5449329254827;5790.652560846363;5807.60617451817;5851.805572910159;5876.324222714357;5896.852578685482;6689.862723337044;6784.423324017415;6789.830254243585;6800.50388914438;6910.756063908444;5752.453516915886;5772.3938986406665;5882.411758824083;5889.411058894162;5894.145348924382;3626.601073881871;3631.2555926035097;3634.2186429917697;3674.401635288658;3674.853005879735;6521.608703477712;6525.1517302750335;6547.770828020996;6605.801825945229;6658.069159943659;6947.201071876698;7029.747003063176;7036.261192574808;7074.292570742885;7090.964545177241;6498.187726534184;6509.772157974448;6567.620799632035;6594.406503414716;6652.201735791736;6665.133992383322;6686.966565167143;6748.291429399842;6777.45780337562;6819.69311380999;6719.697613607497;6731.225909020525;6762.966185169043;6801.18385793708;6810.489213309121;6507.349265073455;6584.604923704402;6613.900791488035;6663.466922646274;6686.732530698718;5004.649674522894;5023.623228257822;5040.865805193948;5052.7726252318;5111.207762796773;6716.966415167893;6825.453963682993;6851.9657401712675;6862.210845752802;6866.553674011173;6555.705503074077;6606.9008964866525;6618.801435956929;6626.006099085176;6634.369734875372;6557.491776891246;6568.408843204134;6585.6377899216;6627.271000190071;6682.83292917681;6341.736358822259;6490.902636460362;6551.705173267119;6566.573172743756;6608.43828274602;6107.389261073857;6457.321096514846;6608.8347791305605;6663.26704062543;6693.263740988585;6308.318964440003;6405.231372235362;6454.578613649665;6471.7734879279;6505.772297969548 03:35:57 INFO - PID 6681 | |5;yui.html;892.049966785374;902.0933961368825;918.5407296351824;921.0605182713632;922.8569571716382;18400.263989440424;20468.69296960545;21157.894210528924;21171.47071323218;21284.680729789026;19864.60270794587;19916.10377533011;20594.1762329507;20832.37502874916;21787.564248715014;982.1700662939822;998.4508520313834;1002.4787110702467;1002.7844013537089;1003.523326419952;999.3604093380243;999.7550600102985;1001.1340190735035;1005.1104772276515;1007.7833265847842;1035.0529265721855;1038.2213339995003;1039.552992213348;1040.1003132290552;1040.1314902056793;1017.7404761785772;1026.425201886942;1034.7516596016976;1037.190991027;1037.419045334615;758.0789340950716;763.1452772894338;765.555977533035;766.0730516075555;766.3371183925864;18711.064446777742;19437.22251109954;19470.415887523395;19530.609387812307;19578.902105489524;19465.221391144336;20927.68608470874;21029.8948505258;21037;21097.47256318589;14792.482263120739;15413.075215487106;15459.845401545954;15478.380864765395;15590.532284031497;3640.526731524885;3725.217704282104;3726.4782930389765;3728.384816505273;3728.7949162796035;126939.36530317289;128392;131867.340663297;132103.33948330197;132838.3358083213;15970.281337339848;16191.67616647684;16477.928934619213;16511.504654860375;16516.75224871628;29750;30932.381352372828;31092;31187.84406077955;31262.218444538612;8490.787730306793;8492.957535212408;8521.744347669579;8681.6527338907;8752.124787521198;3877;3960.2871483132735;3974.364101743707;3981.5222173339384;3981.5620281768984;4806.375171227788;5114.64197506171;5231.633785635046;5245.527902488794;5308.654937429054;976.4678250353575;977.3744803325877;1000.9099181073739;1005.5475036233578;1007.5466040281756;583.7927535724757;588.4821357205717;602.2471910112359;602.5571205164201;624.7906951171318;10327.741806454898;10368.170546356274;10435.32170408921;10909.672709818718;11094.167937404565;684.6234570985938;689.407109885498;700.3976580141075;706.0820932787356;707.1620130145793;4804.351412559329;4809.0862736080035;4828.961773218704;4872.561469467765;4943.110240156734;109508.4524577388;111529.44235278934;113419.43290283496;114537.42731286457;115062;92814;94904.52547737217;95138;96352.51823740837;97272.51363743136;81721.59139204265;82362;82534.17465825385;82643.5867820657;83851;9042.638294468148;9072.183503484717;9074.27405807547;9155.542222888696;9242.630294788134;40950.385744213265;41083.58916410798;41211;41561.1687766249;42109.78945105255;52070.739646301525;53563.73218133884;82084;82929;83422;15014;16757.581060473585;16884.915575422045;17029.659406812043;18479.44561663152;18742.25030998745;19770.40688779339;19788.60422791565;19795.406137815888;20040.398788036382;16899.831001689825;17333.91333043377;17494;17950.5512362192;17985.640287194445;16330.67338653244;16760.58098547546;17372.30510779605;17722.5569360767;18743.062846857876;17316.913415432842;17505.474835754947;17550.561235969202;17622.29510819553;17719.114044297476;11731.178817482694;11778.646640600795;12059.81910271329;12257.938710306391;12389.256644601353;13379.866201337862;13884.09753366028;13936.303184840921;14074.648133796736;14096.224707641179 03:35:57 INFO - PID 6681 | __end_tp_report 03:35:57 INFO - PID 6681 | __start_cc_report 03:35:57 INFO - PID 6681 | _x_x_mozilla_cycle_collect,367 03:35:57 INFO - PID 6681 | __end_cc_report 03:35:57 INFO - PID 6681 | __startTimestamp1504521357881__endTimestamp 03:35:57 INFO - PID 6681 | 03:35:57 INFO - PID 6681 | ------- Summary: start ------- 03:35:57 INFO - PID 6681 | Number of tests: 6 03:35:57 INFO - PID 6681 | 03:35:57 INFO - PID 6681 | [#0] dojo.html Cycles:165 Average:6332.30 Median:6604.64 stddev:746.66 (11.3%) stddev-sans-first:710.87 03:35:57 INFO - PID 6681 | Values: 3331.2 3333.8 3369.6 3402.5 3408.0 6454.5 6492.2 6532.0 6545.0 6556.6 6226.6 6347.5 6356.3 6382.6 6413.0 5813.4 5861.6 5988.9 6020.2 6039.2 5912.1 5968.8 6002.8 6010.1 6025.8 5739.3 5740.7 5838.8 5862.4 5997.9 5951.2 5952.3 5976.3 6015.1 6028.5 5784.0 5817.6 5842.5 5961.7 5995.7 6231.1 6315.2 6322.1 6344.9 6360.0 6773.2 6843.6 6856.1 6864.7 6954.4 6937.9 7057.8 7077.0 7078.3 7090.3 6403.1 6422.3 6439.8 6526.2 6583.5 6958.9 6972.5 6975.8 6995.9 7264.4 6696.9 6757.1 6764.2 6768.9 6780.9 6859.6 6871.0 6876.5 6905.1 6914.7 6800.3 6823.6 6841.4 6848.1 6898.8 6863.1 6868.1 6909.7 6938.4 7018.8 5348.7 5430.5 5435.6 5442.3 5536.4 4657.2 4699.3 4756.1 4858.0 4858.2 5947.3 5986.7 6042.8 6047.8 6071.2 6713.7 6733.0 6777.1 6785.4 6806.5 5845.7 5930.9 5939.3 5947.4 5955.0 5015.9 5810.4 5817.3 5840.4 5922.1 6642.3 6689.7 6731.8 6740.4 6786.9 6701.3 6742.2 6833.8 6880.3 6975.3 6596.6 6778.5 6818.7 6850.6 6852.5 6781.2 6796.0 6831.9 6912.6 6945.3 6906.2 6978.4 6983.2 6996.9 7053.0 6663.8 6663.8 6666.6 6671.2 6682.4 6693.5 6747.3 6752.3 6811.0 6813.0 6526.9 6604.6 6623.8 6691.5 6729.2 6608.2 6728.3 6811.5 6814.1 6875.3 6299.5 6383.6 6407.6 6418.6 6427.3 03:35:57 INFO - PID 6681 | 03:35:57 INFO - PID 6681 | [#1] ext.html Cycles:180 Average:36901.98 Median:3533.03 stddev:51611.91 (1460.8%) stddev-sans-first:51685.44 03:35:57 INFO - PID 6681 | Values: 783.5 791.9 813.4 841.3 842.5 40835.6 41106.8 41371.6 41481.6 41592.8 37360.6 37647.8 37740.2 37851.4 37858.6 6820.1 6875.6 6945.2 6948.9 6961.3 6848.0 6898.9 6903.3 6910.4 6911.3 6833.2 6873.2 7718.7 7903.0 7957.4 7825.7 7872.1 7884.2 7898.8 7943.6 7708.1 7777.4 7781.6 7787.3 7790.2 38380.4 39323.4 39344.2 39528.8 39643.6 3443.4 3505.5 3528.1 3531.1 3534.9 31918.7 31935.5 31941.8 31955.5 32081.4 5083.7 5118.1 5132.7 5154.3 5192.2 181175.0 182257.0 183929.1 184019.0 185047.0 47380.5 47724.3 47834.3 48044.5 48188.3 49804.8 49912.8 50125.7 50240.0 50333.2 15344.4 15607.9 15654.2 15715.1 15772.0 12868.1 12892.1 12894.1 12918.5 12954.6 13994.7 14077.2 14119.9 14170.3 14235.7 5439.9 5524.1 5594.4 5714.5 5755.3 981.2 984.8 989.7 1014.6 1015.3 22154.8 22376.4 22404.1 22411.4 22449.3 1095.3 1109.4 1112.4 1116.5 1116.8 11831.6 11871.4 12027.0 12106.8 12111.2 221401.0 223133.0 223191.0 223350.0 223411.0 45284.8 45961.0 50231.7 51103.5 51587.7 1443.8 1445.3 1453.1 1456.8 1460.1 26699.3 27009.0 27231.3 27572.3 27861.3 36914.6 36936.3 36955.3 37050.3 37059.6 188765.0 188888.0 191074.0 191153.0 191435.2 33322.7 34713.7 34810.0 34842.3 35034.0 41985.6 42347.4 42563.7 42613.0 42974.6 30692.4 30956.8 31051.1 31208.0 31279.8 31553.4 31942.7 33160.7 34431.3 34672.5 38301.4 38602.4 39150.4 39472.8 39511.4 33271.2 33515.0 34016.3 34029.0 34081.1 23583.6 24273.8 24363.5 24398.4 24795.3 03:35:57 INFO - PID 6681 | 03:35:57 INFO - PID 6681 | [#2] jquery.html Cycles:180 Average:33912.48 Median:23295.59 stddev:73718.57 (316.4%) stddev-sans-first:73882.87 03:35:57 INFO - PID 6681 | Values: 572.0 582.7 589.1 593.5 600.3 22079.2 22568.7 22746.3 22857.8 23032.1 24145.3 25086.1 26509.7 26629.7 26658.3 7167.8 7323.1 7343.3 7352.8 7376.2 7233.4 7248.9 7254.0 7318.8 7326.5 4313.7 4313.9 4320.3 5469.6 7131.6 6979.0 7079.9 7083.4 7095.0 7120.5 6907.3 6917.6 6924.4 6958.6 6958.7 29134.4 29815.9 30397.7 31142.0 31219.1 2079.5 2104.3 2110.6 2115.2 2118.5 20541.3 21092.6 21097.9 21237.3 21255.8 2548.2 2559.3 2649.3 2661.6 2667.2 140681.3 143670.0 144105.0 145456.5 146003.0 19834.7 21006.5 21049.7 21881.2 23276.0 41487.4 41627.0 41693.0 41715.4 41823.8 9721.5 9920.8 10149.3 10162.3 10326.8 9337.4 9401.5 9439.5 9440.3 9485.2 8778.6 8901.2 9070.4 9096.2 9562.3 2764.6 2769.5 2804.6 2836.7 2851.3 958.4 976.0 981.1 983.8 987.8 19891.7 20004.2 20031.8 20502.3 20780.7 1044.3 1048.2 1054.2 1055.5 1056.0 11126.9 11197.8 11215.2 11278.8 11372.8 415000.0 429198.0 430153.0 443002.0 443190.0 105940.0 109659.5 109915.0 110635.4 110762.0 1188.0 1205.1 1205.4 1220.7 1221.8 17594.4 17911.2 17949.3 18087.0 18363.5 52754.7 54198.5 55835.0 56073.2 57245.7 69951.6 70503.6 70949.6 71090.0 71806.3 14270.0 14748.6 14751.1 14802.1 14812.1 21932.3 22933.0 23085.8 23315.2 23367.0 17733.0 18330.2 18605.3 18669.6 18714.3 16177.2 16178.2 16218.4 16472.9 16514.8 18778.2 18785.3 18816.1 18914.9 19045.7 13870.8 14370.1 14653.5 14737.3 14802.2 15972.8 16129.6 18311.5 18767.7 18856.0 03:35:57 INFO - PID 6681 | 03:35:57 INFO - PID 6681 | [#3] mootools.html Cycles:180 Average:9461.82 Median:26553.40 stddev:6784.50 (25.6%) stddev-sans-first:6775.85 03:35:57 INFO - PID 6681 | Values: 1304.2 1337.7 1369.2 1377.5 1378.4 15159.0 15760.5 15789.8 15799.2 15810.8 15148.1 15964.3 16140.8 16302.7 16334.8 2795.5 2826.9 2852.5 2854.1 2857.3 2718.3 2777.4 2788.7 2826.6 2842.9 2735.6 2838.5 2841.8 2844.3 2850.3 2604.4 2605.3 2631.4 2634.8 2637.3 2810.9 2825.4 2832.8 2845.6 2849.0 15075.1 15214.2 15434.2 15434.7 15459.0 8129.0 8382.7 8418.5 8440.5 8449.2 5128.4 5288.2 5330.1 5354.7 5462.5 1876.8 1909.4 1918.5 1940.1 1978.8 26482.1 26484.0 26515.5 26591.3 26652.3 6568.7 6752.9 6826.8 6924.2 6927.2 16208.9 16216.3 16217.4 16246.7 16303.9 7917.2 8016.2 8085.1 8107.6 8192.9 7077.7 7360.0 7505.0 7533.4 7658.7 5137.6 5214.8 5323.4 5342.9 5370.4 1413.1 1472.7 1558.7 1560.2 1560.8 747.6 765.2 765.7 787.1 787.5 8286.7 8701.7 8730.2 8800.6 8830.6 976.0 992.0 1000.7 1005.1 1008.8 5108.3 5136.6 5329.0 5362.9 5500.7 21653.8 22268.8 22306.2 22388.8 22606.7 20874.6 21991.3 22520.5 22530.8 22543.5 10734.2 10825.5 10913.7 10989.5 11014.4 8807.4 9281.8 9335.3 9407.1 9437.9 18631.3 18804.2 19264.5 19311.2 19587.1 19484.0 19650.8 20059.9 20299.4 20647.2 11537.3 11583.5 11683.2 11803.6 11830.0 13688.7 13885.8 13927.6 13973.2 14014.9 11189.5 11213.1 11298.3 11555.1 11790.5 10214.8 10397.2 10481.9 10537.7 10598.5 9554.4 9668.8 9932.0 9947.0 9952.3 7158.7 7186.6 7231.7 7252.9 7288.5 7057.9 7065.2 7075.9 7119.8 7181.6 03:35:57 INFO - PID 6681 | 03:35:57 INFO - PID 6681 | [#4] prototype.html Cycles:180 Average:5590.52 Median:6254.31 stddev:1573.47 (25.2%) stddev-sans-first:1530.69 03:35:57 INFO - PID 6681 | Values: 494.7 498.7 527.2 532.6 533.0 6324.4 6381.0 6390.3 6402.0 6480.8 6120.6 6225.8 6282.9 6309.9 6348.4 5013.3 5054.8 5112.8 5114.3 5148.2 4943.9 5027.1 5040.7 5051.2 5096.9 4901.7 4988.0 4998.6 5049.9 5065.0 4986.2 5012.3 5069.2 5108.6 5110.2 4497.6 4518.8 4522.1 4535.3 4537.6 6158.3 6167.4 6208.4 6283.8 6298.3 5763.1 5806.4 5809.2 5840.7 5867.6 6728.7 6731.5 6736.2 6740.5 6787.3 4851.0 4896.3 4905.4 4923.1 4924.4 6922.2 7000.4 7029.2 7114.4 7120.2 5063.1 5090.4 5096.1 5107.5 5126.2 5135.3 5161.3 5169.4 5189.0 5203.5 5756.5 5785.9 5790.3 5804.4 5819.7 6598.5 6668.2 6725.2 6727.5 6751.4 1760.8 1799.1 1799.2 1806.3 1807.3 1142.7 1157.3 1167.4 1191.8 1197.5 5790.7 5807.6 5851.8 5876.3 5896.9 6689.9 6784.4 6789.8 6800.5 6910.8 5752.5 5772.4 5882.4 5889.4 5894.1 3626.6 3631.3 3634.2 3674.4 3674.9 6521.6 6525.2 6547.8 6605.8 6658.1 6947.2 7029.7 7036.3 7074.3 7091.0 6498.2 6509.8 6567.6 6594.4 6652.2 6665.1 6687.0 6748.3 6777.5 6819.7 6719.7 6731.2 6763.0 6801.2 6810.5 6507.3 6584.6 6613.9 6663.5 6686.7 5004.6 5023.6 5040.9 5052.8 5111.2 6717.0 6825.5 6852.0 6862.2 6866.6 6555.7 6606.9 6618.8 6626.0 6634.4 6557.5 6568.4 6585.6 6627.3 6682.8 6341.7 6490.9 6551.7 6566.6 6608.4 6107.4 6457.3 6608.8 6663.3 6693.3 6308.3 6405.2 6454.6 6471.8 6505.8 03:35:57 INFO - PID 6681 | 03:35:57 INFO - PID 6681 | [#5] yui.html Cycles:180 Average:23497.21 Median:20880.03 stddev:32627.99 (156.3%) stddev-sans-first:32675.37 03:35:57 INFO - PID 6681 | Values: 892.0 902.1 918.5 921.1 922.9 18400.3 20468.7 21157.9 21171.5 21284.7 19864.6 19916.1 20594.2 20832.4 21787.6 982.2 998.5 1002.5 1002.8 1003.5 999.4 999.8 1001.1 1005.1 1007.8 1035.1 1038.2 1039.6 1040.1 1040.1 1017.7 1026.4 1034.8 1037.2 1037.4 758.1 763.1 765.6 766.1 766.3 18711.1 19437.2 19470.4 19530.6 19578.9 19465.2 20927.7 21029.9 21037.0 21097.5 14792.5 15413.1 15459.8 15478.4 15590.5 3640.5 3725.2 3726.5 3728.4 3728.8 126939.4 128392.0 131867.3 132103.3 132838.3 15970.3 16191.7 16477.9 16511.5 16516.8 29750.0 30932.4 31092.0 31187.8 31262.2 8490.8 8493.0 8521.7 8681.7 8752.1 3877.0 3960.3 3974.4 3981.5 3981.6 4806.4 5114.6 5231.6 5245.5 5308.7 976.5 977.4 1000.9 1005.5 1007.5 583.8 588.5 602.2 602.6 624.8 10327.7 10368.2 10435.3 10909.7 11094.2 684.6 689.4 700.4 706.1 707.2 4804.4 4809.1 4829.0 4872.6 4943.1 109508.5 111529.4 113419.4 114537.4 115062.0 92814.0 94904.5 95138.0 96352.5 97272.5 81721.6 82362.0 82534.2 82643.6 83851.0 9042.6 9072.2 9074.3 9155.5 9242.6 40950.4 41083.6 41211.0 41561.2 42109.8 52070.7 53563.7 82084.0 82929.0 83422.0 15014.0 16757.6 16884.9 17029.7 18479.4 18742.3 19770.4 19788.6 19795.4 20040.4 16899.8 17333.9 17494.0 17950.6 17985.6 16330.7 16760.6 17372.3 17722.6 18743.1 17316.9 17505.5 17550.6 17622.3 17719.1 11731.2 11778.6 12059.8 12257.9 12389.3 13379.9 13884.1 13936.3 14074.6 14096.2 03:35:57 INFO - PID 6681 | -------- Summary: end -------- 03:35:57 INFO - PID 6681 | 03:35:58 INFO - TEST-INFO | 6681: exit 0 03:35:58 INFO - TEST-OK | dromaeo_css | took 1115890ms 03:35:58 INFO - TEST-START | kraken 03:35:58 INFO - Initialising browser for kraken test... 03:35:58 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33223/getInfo.html -profile /tmp/tmp6IZQu3/profile 03:35:58 INFO - TEST-INFO | started process 7063 (/builds/slave/test/build/application/firefox/firefox http://localhost:33223/getInfo.html) 03:36:04 INFO - TEST-INFO | 7063: exit 0 03:36:05 INFO - Browser initialized. 03:36:05 INFO - Running cycle 1/1 for kraken test... 03:36:05 INFO - Using env: {'DISPLAY': ':0', 03:36:05 INFO - 'HOME': '/home/cltbld', 03:36:05 INFO - 'JSGC_DISABLE_POISONING': '1', 03:36:05 INFO - 'LANG': 'en_US.UTF-8', 03:36:05 INFO - 'LANGUAGE': 'en_US:en', 03:36:05 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:36:05 INFO - 'LOGNAME': 'cltbld', 03:36:05 INFO - 'MAIL': '/var/mail/cltbld', 03:36:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:36:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:36:05 INFO - 'MOZ_ACCELERATED': '1', 03:36:05 INFO - 'MOZ_CRASHREPORTER': '1', 03:36:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:05 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:36:05 INFO - 'MOZ_NO_REMOTE': '1', 03:36:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:36:05 INFO - 'MOZ_WEBRENDER': '1', 03:36:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:36:05 INFO - 'NO_EM_RESTART': '1', 03:36:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:36:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:05 INFO - 'PWD': '/builds/slave/test', 03:36:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:36:05 INFO - 'RUST_BACKTRACE': 'full', 03:36:05 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:36:05 INFO - 'SHELL': '/bin/bash', 03:36:05 INFO - 'SHLVL': '1', 03:36:05 INFO - 'TERM': 'linux', 03:36:05 INFO - 'TMOUT': '86400', 03:36:05 INFO - 'USER': 'cltbld', 03:36:05 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787', 03:36:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:05 INFO - '_': '/tools/buildbot/bin/python'} 03:36:05 INFO - TEST-INFO | started process 7257 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6IZQu3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:36:06 INFO - PID 7257 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:36:06 INFO - PID 7257 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:36:06 INFO - PID 7257 | 03:36:06 INFO - PID 7257 | (/builds/slave/test/build/application/firefox/firefox:7320): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:36:06 INFO - PID 7257 | 03:36:16 INFO - PID 7257 | RSS: Main: 206618624 03:36:16 INFO - PID 7257 | 03:36:19 INFO - PID 7257 | Cycle 1(1): loaded http://localhost:33223/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33223/tests/kraken/driver.html?testName=audio-beat-detection) 03:36:20 INFO - PID 7257 | RSS: Main: 211922944 03:36:20 INFO - PID 7257 | 03:36:23 INFO - PID 7257 | Cycle 1(1): loaded http://localhost:33223/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33223/tests/kraken/driver.html?testName=audio-dft) 03:36:23 INFO - PID 7257 | RSS: Main: 213286912 03:36:23 INFO - PID 7257 | 03:36:26 INFO - PID 7257 | Cycle 1(1): loaded http://localhost:33223/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33223/tests/kraken/driver.html?testName=audio-fft) 03:36:27 INFO - PID 7257 | RSS: Main: 207818752 03:36:27 INFO - PID 7257 | 03:36:29 INFO - PID 7257 | Cycle 1(1): loaded http://localhost:33223/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33223/tests/kraken/driver.html?testName=audio-oscillator) 03:36:30 INFO - PID 7257 | RSS: Main: 208805888 03:36:30 INFO - PID 7257 | 03:36:32 INFO - PID 7257 | Cycle 1(1): loaded http://localhost:33223/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33223/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:36:33 INFO - PID 7257 | RSS: Main: 208183296 03:36:33 INFO - PID 7257 | 03:36:36 INFO - PID 7257 | Cycle 1(1): loaded http://localhost:33223/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33223/tests/kraken/driver.html?testName=imaging-darkroom) 03:36:36 INFO - PID 7257 | RSS: Main: 211697664 03:36:36 INFO - PID 7257 | 03:36:40 INFO - PID 7257 | Cycle 1(1): loaded http://localhost:33223/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33223/tests/kraken/driver.html?testName=imaging-desaturate) 03:36:41 INFO - PID 7257 | RSS: Main: 210001920 03:36:41 INFO - PID 7257 | 03:36:43 INFO - PID 7257 | Cycle 1(1): loaded http://localhost:33223/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33223/tests/kraken/driver.html?testName=json-parse-financial) 03:36:44 INFO - PID 7257 | RSS: Main: 211054592 03:36:44 INFO - PID 7257 | 03:36:46 INFO - PID 7257 | Cycle 1(1): loaded http://localhost:33223/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33223/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:36:46 INFO - PID 7257 | RSS: Main: 212312064 03:36:46 INFO - PID 7257 | 03:36:48 INFO - PID 7257 | Cycle 1(1): loaded http://localhost:33223/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33223/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:36:49 INFO - PID 7257 | RSS: Main: 212058112 03:36:49 INFO - PID 7257 | 03:36:51 INFO - PID 7257 | Cycle 1(1): loaded http://localhost:33223/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33223/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:36:52 INFO - PID 7257 | RSS: Main: 210243584 03:36:52 INFO - PID 7257 | 03:36:54 INFO - PID 7257 | Cycle 1(1): loaded http://localhost:33223/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:36:55 INFO - PID 7257 | RSS: Main: 212426752 03:36:55 INFO - PID 7257 | 03:36:58 INFO - PID 7257 | Cycle 1(1): loaded http://localhost:33223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:36:59 INFO - PID 7257 | RSS: Main: 212652032 03:36:59 INFO - PID 7257 | 03:37:00 INFO - PID 7257 | Cycle 1(1): loaded http://localhost:33223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33223/tests/kraken/driver.html?testName=ai-astar) 03:37:01 INFO - PID 7257 | RSS: Main: 215457792 03:37:01 INFO - PID 7257 | 03:37:01 INFO - PID 7257 | __start_tp_report 03:37:01 INFO - PID 7257 | _x_x_mozilla_page_load 03:37:01 INFO - PID 7257 | _x_x_mozilla_page_load_details 03:37:01 INFO - PID 7257 | |i|pagename|runs| 03:37:01 INFO - PID 7257 | |0;ai-astar;106;112;102;118;116;111;108;100;113;107 03:37:01 INFO - PID 7257 | |1;audio-beat-detection;152;116;140;178;127;143;144;127;172;124 03:37:01 INFO - PID 7257 | |2;audio-dft;164;167;172;162;169;164;165;178;162;167 03:37:01 INFO - PID 7257 | |3;audio-fft;73;75;74;174;80;75;75;86;99;80 03:37:01 INFO - PID 7257 | |4;audio-oscillator;79;79;135;81;79;80;140;80;80;125 03:37:01 INFO - PID 7257 | |5;imaging-gaussian-blur;117;115;125;120;119;115;115;121;115;115 03:37:01 INFO - PID 7257 | |6;imaging-darkroom;153;150;153;151;150;149;152;157;155;150 03:37:01 INFO - PID 7257 | |7;imaging-desaturate;96;99;94;96;95;99;98;96;94;95 03:37:01 INFO - PID 7257 | |8;json-parse-financial;64;53;53;62;53;58;53;61;54;53 03:37:01 INFO - PID 7257 | |9;json-stringify-tinderbox;39;48;41;44;42;39;38;43;39;41 03:37:01 INFO - PID 7257 | |10;stanford-crypto-aes;82;76;79;76;83;81;77;77;75;78 03:37:01 INFO - PID 7257 | |11;stanford-crypto-ccm;136;135;126;125;117;121;123;139;121;160 03:37:01 INFO - PID 7257 | |12;stanford-crypto-pbkdf2;180;179;178;182;173;176;185;170;168;168 03:37:01 INFO - PID 7257 | |13;stanford-crypto-sha256-iterative;72;67;66;66;68;65;69;66;65;67 03:37:01 INFO - PID 7257 | __end_tp_report 03:37:01 INFO - PID 7257 | __start_cc_report 03:37:01 INFO - PID 7257 | _x_x_mozilla_cycle_collect,946 03:37:01 INFO - PID 7257 | __end_cc_report 03:37:01 INFO - PID 7257 | __startTimestamp1504521421137__endTimestamp 03:37:01 INFO - PID 7257 | 03:37:01 INFO - PID 7257 | ------- Summary: start ------- 03:37:01 INFO - PID 7257 | Number of tests: 14 03:37:01 INFO - PID 7257 | 03:37:01 INFO - PID 7257 | [#0] ai-astar Cycles:10 Average:109.30 Median:111.50 stddev:5.79 (5.2%) stddev-sans-first:6.02 03:37:01 INFO - PID 7257 | Values: 106.0 112.0 102.0 118.0 116.0 111.0 108.0 100.0 113.0 107.0 03:37:01 INFO - PID 7257 | 03:37:01 INFO - PID 7257 | [#1] audio-beat-detection Cycles:10 Average:142.30 Median:143.50 stddev:20.42 (14.2%) stddev-sans-first:21.36 03:37:01 INFO - PID 7257 | Values: 152.0 116.0 140.0 178.0 127.0 143.0 144.0 127.0 172.0 124.0 03:37:01 INFO - PID 7257 | 03:37:01 INFO - PID 7257 | [#2] audio-dft Cycles:10 Average:167.00 Median:167.00 stddev:4.97 (3.0%) stddev-sans-first:5.15 03:37:01 INFO - PID 7257 | Values: 164.0 167.0 172.0 162.0 169.0 164.0 165.0 178.0 162.0 167.0 03:37:01 INFO - PID 7257 | 03:37:01 INFO - PID 7257 | [#3] audio-fft Cycles:10 Average:89.10 Median:77.50 stddev:30.85 (39.8%) stddev-sans-first:32.17 03:37:01 INFO - PID 7257 | Values: 73.0 75.0 74.0 174.0 80.0 75.0 75.0 86.0 99.0 80.0 03:37:01 INFO - PID 7257 | 03:37:01 INFO - PID 7257 | [#4] audio-oscillator Cycles:10 Average:95.80 Median:79.50 stddev:26.16 (32.9%) stddev-sans-first:27.03 03:37:01 INFO - PID 7257 | Values: 79.0 79.0 135.0 81.0 79.0 80.0 140.0 80.0 80.0 125.0 03:37:01 INFO - PID 7257 | 03:37:01 INFO - PID 7257 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:3.47 (2.9%) stddev-sans-first:3.67 03:37:01 INFO - PID 7257 | Values: 117.0 115.0 125.0 120.0 119.0 115.0 115.0 121.0 115.0 115.0 03:37:01 INFO - PID 7257 | 03:37:01 INFO - PID 7257 | [#6] imaging-darkroom Cycles:10 Average:152.00 Median:152.50 stddev:2.54 (1.7%) stddev-sans-first:2.67 03:37:01 INFO - PID 7257 | Values: 153.0 150.0 153.0 151.0 150.0 149.0 152.0 157.0 155.0 150.0 03:37:01 INFO - PID 7257 | 03:37:01 INFO - PID 7257 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.00 stddev:1.87 (2.0%) stddev-sans-first:1.99 03:37:01 INFO - PID 7257 | Values: 96.0 99.0 94.0 96.0 95.0 99.0 98.0 96.0 94.0 95.0 03:37:01 INFO - PID 7257 | 03:37:01 INFO - PID 7257 | [#8] json-parse-financial Cycles:10 Average:56.40 Median:56.00 stddev:4.43 (7.9%) stddev-sans-first:3.75 03:37:01 INFO - PID 7257 | Values: 64.0 53.0 53.0 62.0 53.0 58.0 53.0 61.0 54.0 53.0 03:37:01 INFO - PID 7257 | 03:37:01 INFO - PID 7257 | [#9] json-stringify-tinderbox Cycles:10 Average:41.40 Median:41.50 stddev:3.03 (7.3%) stddev-sans-first:3.08 03:37:01 INFO - PID 7257 | Values: 39.0 48.0 41.0 44.0 42.0 39.0 38.0 43.0 39.0 41.0 03:37:01 INFO - PID 7257 | 03:37:01 INFO - PID 7257 | [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:78.50 stddev:2.76 (3.5%) stddev-sans-first:2.60 03:37:01 INFO - PID 7257 | Values: 82.0 76.0 79.0 76.0 83.0 81.0 77.0 77.0 75.0 78.0 03:37:01 INFO - PID 7257 | 03:37:01 INFO - PID 7257 | [#11] stanford-crypto-ccm Cycles:10 Average:130.30 Median:130.50 stddev:12.75 (9.8%) stddev-sans-first:13.35 03:37:01 INFO - PID 7257 | Values: 136.0 135.0 126.0 125.0 117.0 121.0 123.0 139.0 121.0 160.0 03:37:01 INFO - PID 7257 | 03:37:01 INFO - PID 7257 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.90 Median:178.50 stddev:5.95 (3.3%) stddev-sans-first:6.13 03:37:01 INFO - PID 7257 | Values: 180.0 179.0 178.0 182.0 173.0 176.0 185.0 170.0 168.0 168.0 03:37:01 INFO - PID 7257 | 03:37:01 INFO - PID 7257 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:2.13 (3.2%) stddev-sans-first:1.33 03:37:01 INFO - PID 7257 | Values: 72.0 67.0 66.0 66.0 68.0 65.0 69.0 66.0 65.0 67.0 03:37:01 INFO - PID 7257 | -------- Summary: end -------- 03:37:01 INFO - PID 7257 | 03:37:01 INFO - PID 7257 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:37:02 INFO - TEST-INFO | 7257: exit 0 03:37:02 INFO - TEST-OK | kraken | took 63283ms 03:37:02 INFO - SUITE-END | took 1179s 03:37:02 INFO - Completed test suite (00:19:39) 03:37:02 INFO - javascript benchmark 03:37:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8598.082676535474, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3331.2005118771485, 3333.783304085235, 3369.5956485221777, 3402.5406570113028, 3407.965920340796, 6454.451371633405, 6492.156019717436, 6531.999999999994, 6545.018247262901, 6556.606603603787, 6226.564140510166, 6347.49220062395, 6356.269029061659, 6382.585131966419, 6412.967935160318, 5813.418658134195, 5861.589688721791, 5988.940110598882, 6020.217371741689, 6039.214902062748, 5912.054071348585, 5968.7612495500125, 6002.759889604411, 6010.098485227207, 6025.849353766147, 5739.261700269813, 5740.655560666352, 5838.824835254928, 5862.38444963277, 5997.910031349533, 5951.166836642873, 5952.255968003999, 5976.252968378953, 6015.0676645120075, 6028.547858910555, 5783.9710801446145, 5817.592768506206, 5842.503387212092, 5961.701914904237, 5995.700214989233, 6231.096491008823, 6315.24217093947, 6322.114903913456, 6344.904826427607, 6360.045993101072, 6773.1872175338785, 6843.62360070196, 6856.074429951943, 6864.691088901011, 6954.374106330454, 6937.895931561031, 7057.788266352018, 7077.0, 7078.292170782932, 7090.326418990187, 6403.135576697133, 6422.325655806119, 6439.7746078887485, 6526.184226971629, 6583.506237032146, 6958.860822783516, 6972.546784458994, 6975.79072627822, 6995.8950615740805, 7264.418846492268, 6696.86606267872, 6757.087793147961, 6764.154480689914, 6768.864622707519, 6780.864382712318, 6859.588424694534, 6871.03805467235, 6876.484263680244, 6905.102336696197, 6914.723411063602, 6800.31996800316, 6823.624700641512, 6841.350071743176, 6848.1097457331225, 6898.793036208922, 6863.073485079551, 6868.072810170563, 6909.654517274116, 6938.41023512997, 7018.824529386806, 5348.705821179872, 5430.456954304538, 5435.619506634498, 5442.265294185314, 5536.363318218376, 4657.208274593328, 4699.318598803223, 4756.096341695067, 4858.02839432115, 4858.17411040124, 5947.286325640951, 5986.6707331097205, 6042.758289668452, 6047.758089676364, 6071.210742603434, 6713.69788359525, 6732.966335168293, 6777.051212830208, 6785.42323902464, 6806.523543351917, 5845.707714614252, 5930.911036334458, 5939.2575928009, 5947.405259474018, 5954.970225148933, 5015.899682006339, 5810.360860305363, 5817.301923769175, 5840.445157710011, 5922.111683247547, 6642.269350371455, 6689.665516724242, 6731.798046058626, 6740.4270636995425, 6786.932130678729, 6701.296363881917, 6742.157230346207, 6833.794986150423, 6880.277570854988, 6975.267596902252, 6596.637184954873, 6778.525503214728, 6818.659067046727, 6850.554713943578, 6852.5203235773015, 6781.186257649114, 6796.0, 6831.863362732817, 6912.619805910522, 6945.340192681687, 6906.205786334636, 6978.441724661913, 6983.2318444972025, 6996.930030699831, 7052.9647351762915, 6663.8000859972335, 6663.833404164935, 6666.600003999775, 6671.166104236971, 6682.398584127452, 6693.464522838259, 6747.325267473213, 6752.257251702407, 6811.0, 6812.965935170292, 6526.9020964686515, 6604.636744979072, 6623.768168114093, 6691.498137639599, 6729.226138993882, 6608.173978252718, 6728.3271672832325, 6811.489138314547, 6814.114165158498, 6875.346842049998, 6299.527535434769, 6383.553151279366, 6407.551471396958, 6418.614883107029, 6427.260865000398], "value": 6278.0683198410015, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [783.5416281475336, 791.8693415586429, 813.357447616383, 841.2933136165622, 842.5071333270033, 40835.59164408355, 41106.794466027706, 41371.58628413715, 41481.58518414815, 41592.79203603981, 37360.626393736056, 37647.81176094116, 37740.24519509608, 37851.43222851659, 37858.621413785855, 6820.11338525991, 6875.5530890491955, 6945.201301850276, 6948.930510694904, 6961.338672826098, 6847.965760171217, 6898.862022759542, 6903.309669033081, 6910.378065974061, 6911.308869113099, 6833.214180369271, 6873.243943166248, 7718.729844455417, 7903.012123484565, 7957.442978991444, 7825.726099586515, 7872.094709108468, 7884.172161923001, 7898.763037108896, 7943.5631040292765, 7708.0750309962605, 7777.377809775261, 7781.610919454061, 7787.338076263524, 7790.220977902221, 38380.42429363562, 39323.41014884779, 39344.21311573781, 39528.80235598832, 39643.60356396428, 3443.362977849106, 3505.4917037029486, 3528.0826984984087, 3531.1172206948263, 3534.9469757953652, 31918.680813191804, 31935.520967185512, 31941.84029079863, 31955.520667190012, 32081.358372832412, 5083.669561478529, 5118.104331741967, 5132.717700526469, 5154.278401023859, 5192.221166824968, 181175.0, 182257.0, 183929.0803545987, 184019.0, 185047.0, 47380.52619473761, 47724.284135737995, 47834.28248576274, 48044.519554804705, 48188.27717584239, 49804.75097624489, 49912.75043624759, 50125.74937125291, 50240.0, 50333.24500132501, 15344.386224550894, 15607.921960390126, 15654.217289135499, 15715.135667538396, 15772.0, 12868.099233053596, 12892.097553171376, 12894.097413180987, 12918.547850825176, 12954.611361659165, 13994.650133746534, 14077.225752583501, 14119.858801411854, 14170.291485425894, 14235.715285694228, 5439.864003399867, 5524.14375771756, 5594.412586678422, 5714.457096583982, 5755.280589926259, 981.2003217377815, 984.7538115471132, 989.6734077754323, 1014.6499457687086, 1015.304516406264, 22154.778452215593, 22376.44058898508, 22404.10383584671, 22411.43971400728, 22449.32652020442, 1095.315427857589, 1109.3565731875492, 1112.3881864974394, 1116.547798141754, 1116.843641890136, 11831.585894493823, 11871.406429678482, 12027.0, 12106.81839772404, 12111.212771169845, 221401.0, 223133.0, 223191.0, 223350.0, 223411.0, 45284.77357613257, 45961.0, 50231.74884125556, 51103.48896511061, 51587.742061290206, 1443.8483959184134, 1445.2773613193403, 1453.062774510456, 1456.8178977628008, 1460.0654133273456, 26699.33251668724, 27009.0, 27231.319217019733, 27572.310692232855, 27861.303467413476, 36914.63085369112, 36936.26127477382, 36955.26089478249, 37050.258994819415, 37059.62940370562, 188765.0, 188888.0, 191074.0, 191153.0, 191435.21411964257, 33322.666773331955, 34713.65286347104, 34810.0, 34842.30315393627, 35034.0, 41985.58014419817, 42347.3647895288, 42563.7230883074, 42613.0, 42974.570254298305, 30692.386152277275, 30956.84521577468, 31051.068467946, 31208.0, 31279.84360078185, 31553.36893262076, 31942.68057319397, 33160.668393315755, 34431.31137377188, 34672.47991280082, 38301.42547861728, 38602.42096368501, 39150.412743808294, 39472.802635986634, 39511.407328889516, 33271.16822079467, 33515.0, 34016.319673606886, 34029.0, 34081.14797130092, 23583.646245306678, 24273.757262427855, 24363.51272974495, 24398.390040249134, 24795.25614231576], "value": 16982.882064362017, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.0493118466031, 582.6591444005256, 589.1075021342664, 593.5103539579842, 600.2916558461015, 22079.227227047057, 22568.66147007796, 22746.31761047167, 22857.771422285772, 23032.078716851327, 24145.275641730732, 25086.121985730504, 26509.734902651016, 26629.73370266297, 26658.33354166147, 7167.784966451002, 7323.084614423197, 7343.302386273295, 7352.77941661751, 7376.188619251905, 7233.421326293884, 7248.855022899539, 7253.963730181342, 7318.817029574277, 7326.523775954546, 4313.698041137121, 4313.913721725564, 4320.287152619814, 5469.617126801126, 7131.607761573111, 6978.965105174441, 7079.89380159298, 7083.397911177556, 7095.04216930718, 7120.537165084279, 6907.274736152682, 6917.550359226684, 6924.446044316443, 6958.5824850508625, 6958.65206739661, 29134.417311653648, 29815.850920745474, 30397.69602303971, 31142.0, 31219.063428096968, 2079.5009197792574, 2104.3371338028473, 2110.609537235601, 2115.164510018534, 2118.459792752838, 20541.281055163, 21092.578148436944, 21097.8945105275, 21237.25669601572, 21255.787442125536, 2548.1718441506587, 2559.3473664215694, 2649.3244222723274, 2661.640678508396, 2667.199840047978, 140681.29659351637, 143670.0, 144105.0, 145456.54543454535, 146003.0, 19834.719007314055, 21006.47483812917, 21049.684254736192, 21881.234156804436, 23276.0, 41487.37768933469, 41627.0, 41693.0, 41715.37426938598, 41823.7908810454, 9721.513924303756, 9920.801583968425, 10149.34029288094, 10162.288639795144, 10326.793464130826, 9337.393069450463, 9401.482918439551, 9439.528023598792, 9440.29197810167, 9485.193758530602, 8778.561071946506, 8901.243394311427, 9070.365074444857, 9096.1813436791, 9562.330636855493, 2764.5714914188334, 2769.529180039398, 2804.6213761141985, 2836.673782515037, 2851.2871782054485, 958.3770549143113, 975.9804803903883, 981.0777868803302, 983.7737320416201, 987.7530617345664, 19891.701624475645, 20004.19983200685, 20031.799682003286, 20502.282420115225, 20780.688289675665, 1044.2585764107416, 1048.192891473561, 1054.1882750282393, 1055.5144633468537, 1056.0, 11126.92315347411, 11197.776044479066, 11215.158863085138, 11278.774424511465, 11372.829407558893, 415000.0, 429198.0, 430153.0, 443002.0, 443190.0, 105940.0, 109659.45170274258, 109915.0, 110635.44682276537, 110762.0, 1187.9762404751857, 1205.0660737928176, 1205.4093494187784, 1220.725336799213, 1221.7617564575, 17594.384196553572, 17911.19399627046, 17949.282028718706, 18087.0, 18363.54091147732, 52754.73622631862, 54198.45801541934, 55835.0, 56073.15890261568, 57245.71377143088, 69951.60096798137, 70503.64748176227, 70949.64525177341, 71090.0, 71806.28193717996, 14270.0, 14748.63128421798, 14751.114933104049, 14802.111873287637, 14812.111273323635, 21932.342029739135, 22932.968016439634, 23085.769142308363, 23315.183968561018, 23367.0, 17733.0, 18330.175142118904, 18605.348812791486, 18669.626607467504, 18714.251429943193, 16177.191140443167, 16178.191090445665, 16218.35126594923, 16472.917635412225, 16514.83485165181, 18778.15498302607, 18785.34251301198, 18816.059197040366, 18914.905425472785, 19045.71431428502, 13870.791938120734, 14370.13779173253, 14653.487127950471, 14737.336819842918, 14802.185879776285, 15972.760408593647, 16129.596760081093, 18311.542211444288, 18767.71848422302, 18856.0], "value": 12397.875506622688, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1304.2239867278968, 1337.6722702937782, 1369.2263870912927, 1377.4627895120898, 1378.4279523997545, 15159.0, 15760.527184184479, 15789.763153552705, 15799.210039498008, 15810.762838557403, 15148.091114533136, 15964.28160732767, 16140.83859161408, 16302.673946521063, 16334.754978675299, 2795.4828356754015, 2826.9151925442166, 2852.4580329737387, 2854.0866922584783, 2857.3285277959662, 2718.2660681615953, 2777.4167424840807, 2788.665360156784, 2826.6042754014456, 2842.8862845486155, 2735.644366232387, 2838.5032619291646, 2841.801073924826, 2844.3315820782204, 2850.330172409482, 2604.414006848463, 2605.257501612041, 2631.368471566829, 2634.802389820752, 2637.274749443899, 2810.8594570271607, 2825.3925406037597, 2832.7592154666877, 2845.601615773793, 2849.0455697341417, 15075.09549427038, 15214.16322102284, 15434.228288585526, 15434.691306173814, 15459.0, 8128.9593552031865, 8382.706605268786, 8418.452800568004, 8440.451370660949, 8449.239568438808, 5128.384593848725, 5288.206768984644, 5330.093884039723, 5354.651947623392, 5462.508374246337, 1876.8217019383137, 1909.3508207209616, 1918.472420084474, 1940.0978544976513, 1978.8021197880241, 26482.073127440446, 26484.0, 26515.469690606078, 26591.335216619547, 26652.33369165767, 6568.704408301638, 6752.898706519406, 6826.761063362758, 6924.23833378328, 6927.203371612229, 16208.865379423563, 16216.270267837976, 16217.432389866299, 16246.675066498603, 16303.91848040776, 7917.208279172152, 8016.198380161937, 8085.110637829834, 8107.635156417975, 8192.918070819216, 7077.7168913242895, 7360.0, 7505.0, 7533.4349923755935, 7658.65536050879, 5137.588992880561, 5214.843554693365, 5323.3611966563485, 5342.866428339323, 5370.409254981949, 1413.0673755321643, 1472.7201831651953, 1558.7428074367829, 1560.1653115583335, 1560.84391560843, 747.5925620536821, 765.1545042727817, 765.7434759355567, 787.1498781316164, 787.4684587903142, 8286.668533258602, 8701.738947831574, 8730.17063378978, 8800.603972821238, 8830.602622881986, 975.9853602195973, 992.0, 1000.7398076500162, 1005.1205195453978, 1008.8133695266399, 5108.259302401131, 5136.614753893473, 5328.973355133199, 5362.892742145135, 5500.669959802424, 21653.78346216549, 22268.777312226994, 22306.21928232504, 22388.77611223867, 22606.660900086183, 20874.582508349748, 21991.340259792232, 22520.54958900813, 22530.774692252868, 22543.549129017327, 10734.194935379877, 10825.458727063773, 10913.672589822318, 10989.505472253768, 11014.44927753609, 8807.383483156118, 9281.767955801159, 9335.346525743404, 9407.106324899123, 9437.858432123387, 18631.254749809857, 18804.247830086642, 19264.518387040436, 19311.227550897805, 19587.118579663664, 19484.0, 19650.803491964896, 20059.89970050199, 20299.391018269474, 20647.17411303531, 11537.250078744855, 11583.478743456397, 11683.18217724751, 11803.586874459696, 11830.0, 13688.657783555092, 13885.791713124108, 13927.58217253484, 13973.161610303414, 14014.859851401356, 11189.496472658913, 11213.102951764002, 11298.322100673986, 11555.133364997828, 11790.469428875549, 10214.795704085727, 10397.168226541708, 10481.895181048092, 10537.683869483928, 10598.523066462181, 9554.378965367228, 9668.806623867624, 9931.95034024854, 9946.950265248628, 9952.303338766507, 7158.713651453883, 7186.60473673932, 7231.710731570678, 7252.891206631799, 7288.453365997465, 7057.929420705933, 7065.187503437171, 7075.858482830417, 7119.82200444993, 7181.569105853666], "value": 6738.794677820648, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.68660705826034, 498.6735284144177, 527.1881302793697, 532.5952276270034, 533.0485084124836, 6324.367563243673, 6381.0, 6390.329015453381, 6401.9999999999945, 6480.7731729389425, 6120.602160859541, 6225.7509699611965, 6282.874342513159, 6309.936900630993, 6348.428641422271, 5013.298138260647, 5054.823081192159, 5112.795488180468, 5114.28400023997, 5148.227765835127, 4943.9347604485265, 5027.09512287788, 5040.747962601855, 5051.191809310511, 5096.872578185538, 4901.68139070961, 4987.975060124694, 4998.625103117262, 5049.873753156182, 5065.0, 4986.177280748672, 5012.323336349601, 5069.2142717878605, 5108.64239503235, 5110.156824124052, 4497.595216430537, 4518.841840535599, 4522.140793249305, 4535.319702044687, 4537.591616754475, 6158.261008678943, 6167.444929956326, 6208.441240288351, 6283.780067697654, 6298.338674439164, 5763.060838972113, 5806.361300256968, 5809.2447981762525, 5840.707964601753, 5867.647941123546, 6728.663566821689, 6731.528792984493, 6736.157980252468, 6740.460763138937, 6787.2534021257625, 4850.95149048512, 4896.265560165968, 4905.4358748744, 4923.08922849274, 4924.359833221694, 6922.169339679221, 7000.369966703021, 7029.207552074239, 7114.359707626339, 7120.216776154619, 5063.11395505785, 5090.389153301628, 5096.108181068298, 5107.544349860346, 5126.231065340155, 5135.281060651511, 5161.329027226511, 5169.353830771153, 5189.0, 5203.479652034766, 5756.481916627524, 5785.855353616109, 5790.334111577138, 5804.4195580442465, 5819.679917604488, 6598.47212223021, 6668.199816022108, 6725.159355080615, 6727.501699116432, 6751.358620931003, 1760.7534945107695, 1799.073477159264, 1799.1723807048643, 1806.2594336322045, 1807.313220976047, 1142.662914440242, 1157.2593540134321, 1167.445463404893, 1191.82605133943, 1197.5449329254827, 5790.652560846363, 5807.60617451817, 5851.805572910159, 5876.324222714357, 5896.852578685482, 6689.862723337044, 6784.423324017415, 6789.830254243585, 6800.50388914438, 6910.756063908444, 5752.453516915886, 5772.3938986406665, 5882.411758824083, 5889.411058894162, 5894.145348924382, 3626.601073881871, 3631.2555926035097, 3634.2186429917697, 3674.401635288658, 3674.853005879735, 6521.608703477712, 6525.1517302750335, 6547.770828020996, 6605.801825945229, 6658.069159943659, 6947.201071876698, 7029.747003063176, 7036.261192574808, 7074.292570742885, 7090.964545177241, 6498.187726534184, 6509.772157974448, 6567.620799632035, 6594.406503414716, 6652.201735791736, 6665.133992383322, 6686.966565167143, 6748.291429399842, 6777.45780337562, 6819.69311380999, 6719.697613607497, 6731.225909020525, 6762.966185169043, 6801.18385793708, 6810.489213309121, 6507.349265073455, 6584.604923704402, 6613.900791488035, 6663.466922646274, 6686.732530698718, 5004.649674522894, 5023.623228257822, 5040.865805193948, 5052.7726252318, 5111.207762796773, 6716.966415167893, 6825.453963682993, 6851.9657401712675, 6862.210845752802, 6866.553674011173, 6555.705503074077, 6606.9008964866525, 6618.801435956929, 6626.006099085176, 6634.369734875372, 6557.491776891246, 6568.408843204134, 6585.6377899216, 6627.271000190071, 6682.83292917681, 6341.736358822259, 6490.902636460362, 6551.705173267119, 6566.573172743756, 6608.43828274602, 6107.389261073857, 6457.321096514846, 6608.8347791305605, 6663.26704062543, 6693.263740988585, 6308.318964440003, 6405.231372235362, 6454.578613649665, 6471.7734879279, 6505.772297969548], "value": 5122.606593024731, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [892.049966785374, 902.0933961368825, 918.5407296351824, 921.0605182713632, 922.8569571716382, 18400.263989440424, 20468.69296960545, 21157.894210528924, 21171.47071323218, 21284.680729789026, 19864.60270794587, 19916.10377533011, 20594.1762329507, 20832.37502874916, 21787.564248715014, 982.1700662939822, 998.4508520313834, 1002.4787110702467, 1002.7844013537089, 1003.523326419952, 999.3604093380243, 999.7550600102985, 1001.1340190735035, 1005.1104772276515, 1007.7833265847842, 1035.0529265721855, 1038.2213339995003, 1039.552992213348, 1040.1003132290552, 1040.1314902056793, 1017.7404761785772, 1026.425201886942, 1034.7516596016976, 1037.190991027, 1037.419045334615, 758.0789340950716, 763.1452772894338, 765.555977533035, 766.0730516075555, 766.3371183925864, 18711.064446777742, 19437.22251109954, 19470.415887523395, 19530.609387812307, 19578.902105489524, 19465.221391144336, 20927.68608470874, 21029.8948505258, 21037.0, 21097.47256318589, 14792.482263120739, 15413.075215487106, 15459.845401545954, 15478.380864765395, 15590.532284031497, 3640.526731524885, 3725.217704282104, 3726.4782930389765, 3728.384816505273, 3728.7949162796035, 126939.36530317289, 128392.0, 131867.340663297, 132103.33948330197, 132838.3358083213, 15970.281337339848, 16191.67616647684, 16477.928934619213, 16511.504654860375, 16516.75224871628, 29750.0, 30932.381352372828, 31092.0, 31187.84406077955, 31262.218444538612, 8490.787730306793, 8492.957535212408, 8521.744347669579, 8681.6527338907, 8752.124787521198, 3877.0, 3960.2871483132735, 3974.364101743707, 3981.5222173339384, 3981.5620281768984, 4806.375171227788, 5114.64197506171, 5231.633785635046, 5245.527902488794, 5308.654937429054, 976.4678250353575, 977.3744803325877, 1000.9099181073739, 1005.5475036233578, 1007.5466040281756, 583.7927535724757, 588.4821357205717, 602.2471910112359, 602.5571205164201, 624.7906951171318, 10327.741806454898, 10368.170546356274, 10435.32170408921, 10909.672709818718, 11094.167937404565, 684.6234570985938, 689.407109885498, 700.3976580141075, 706.0820932787356, 707.1620130145793, 4804.351412559329, 4809.0862736080035, 4828.961773218704, 4872.561469467765, 4943.110240156734, 109508.4524577388, 111529.44235278934, 113419.43290283496, 114537.42731286457, 115062.0, 92814.0, 94904.52547737217, 95138.0, 96352.51823740837, 97272.51363743136, 81721.59139204265, 82362.0, 82534.17465825385, 82643.5867820657, 83851.0, 9042.638294468148, 9072.183503484717, 9074.27405807547, 9155.542222888696, 9242.630294788134, 40950.385744213265, 41083.58916410798, 41211.0, 41561.1687766249, 42109.78945105255, 52070.739646301525, 53563.73218133884, 82084.0, 82929.0, 83422.0, 15014.0, 16757.581060473585, 16884.915575422045, 17029.659406812043, 18479.44561663152, 18742.25030998745, 19770.40688779339, 19788.60422791565, 19795.406137815888, 20040.398788036382, 16899.831001689825, 17333.91333043377, 17494.0, 17950.5512362192, 17985.640287194445, 16330.67338653244, 16760.58098547546, 17372.30510779605, 17722.5569360767, 18743.062846857876, 17316.913415432842, 17505.474835754947, 17550.561235969202, 17622.29510819553, 17719.114044297476, 11731.178817482694, 11778.646640600795, 12059.81910271329, 12257.938710306391, 12389.256644601353, 13379.866201337862, 13884.09753366028, 13936.303184840921, 14074.648133796736, 14096.224707641179], "value": 8853.740369122921, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1518.9000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 112.0, 102.0, 118.0, 116.0, 111.0, 108.0, 100.0, 113.0, 107.0], "value": 109.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 116.0, 140.0, 178.0, 127.0, 143.0, 144.0, 127.0, 172.0, 124.0], "value": 142.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 167.0, 172.0, 162.0, 169.0, 164.0, 165.0, 178.0, 162.0, 167.0], "value": 167.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 74.0, 174.0, 80.0, 75.0, 75.0, 86.0, 99.0, 80.0], "value": 89.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 135.0, 81.0, 79.0, 80.0, 140.0, 80.0, 80.0, 125.0], "value": 95.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 115.0, 125.0, 120.0, 119.0, 115.0, 115.0, 121.0, 115.0, 115.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 150.0, 153.0, 151.0, 150.0, 149.0, 152.0, 157.0, 155.0, 150.0], "value": 152.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 99.0, 94.0, 96.0, 95.0, 99.0, 98.0, 96.0, 94.0, 95.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 53.0, 53.0, 62.0, 53.0, 58.0, 53.0, 61.0, 54.0, 53.0], "value": 56.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 48.0, 41.0, 44.0, 42.0, 39.0, 38.0, 43.0, 39.0, 41.0], "value": 41.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 76.0, 79.0, 76.0, 83.0, 81.0, 77.0, 77.0, 75.0, 78.0], "value": 78.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 135.0, 126.0, 125.0, 117.0, 121.0, 123.0, 139.0, 121.0, 160.0], "value": 130.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 179.0, 178.0, 182.0, 173.0, 176.0, 185.0, 170.0, 168.0, 168.0], "value": 175.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 67.0, 66.0, 66.0, 68.0, 65.0, 69.0, 66.0, 65.0, 67.0], "value": 67.1, "unit": "score"}]}]} 03:37:02 INFO - Return code: 0 03:37:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:37:02 INFO - # TBPL SUCCESS # 03:37:02 INFO - Running post-action listener: _package_coverage_data 03:37:02 INFO - Running post-action listener: _resource_record_post_action 03:37:02 INFO - [mozharness: 2017-09-04 10:37:02.403340Z] Finished run-tests step (success) 03:37:02 INFO - Running post-run listener: _resource_record_post_run 03:37:02 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 6594560; Write bytes: 391995392; Read time: 2868; Write time: 218724 03:37:02 INFO - TinderboxPrint: CPU usage
13.3% 03:37:02 INFO - TinderboxPrint: I/O read bytes / time
6,594,560 / 2,868 03:37:02 INFO - TinderboxPrint: I/O write bytes / time
391,995,392 / 218,724 03:37:02 INFO - TinderboxPrint: CPU idle
8,265.1 (86.7%) 03:37:02 INFO - TinderboxPrint: CPU user
1,202.5 (12.6%) 03:37:02 INFO - TinderboxPrint: Swap in / out
0 / 0 03:37:02 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 107237376; Read time: 0; Write time: 62584 03:37:02 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:37:02 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 6594560; Write bytes: 284758016; Read time: 2868; Write time: 156140 03:37:03 INFO - Running post-run listener: _upload_blobber_files 03:37:03 INFO - Blob upload gear active. 03:37:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:37:03 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 03:37:03 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'] 03:37:03 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 03:37:03 INFO - (blobuploader) - INFO - Open directory for files ... 03:37:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:37:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:37:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:37:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:37:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:37:04 INFO - (blobuploader) - INFO - Done attempting. 03:37:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:37:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:37:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:37:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:37:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:37:08 INFO - (blobuploader) - INFO - Done attempting. 03:37:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:37:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:37:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:37:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:37:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:37:09 INFO - (blobuploader) - INFO - Done attempting. 03:37:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:37:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:37:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:37:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:37:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:37:10 INFO - (blobuploader) - INFO - Done attempting. 03:37:10 INFO - (blobuploader) - INFO - Iteration through files over. 03:37:10 INFO - Return code: 0 03:37:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:37:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:37:10 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a15f732e63ef4069a6fe56041ecd553ebdf7f95ba14cdcbbf90b65afca48c717fa963bddf973cabd4cae8ebfd333e832f45a3669f11ab8916014f18b2e296593", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66eb9978a35e5c8aa9db6478e20e0c21dbf2828e2255ec42dc589f76d9f6fc0d0ddc2f27934c9489e43f4fcfd747b8b409ec250478d8949755df76f3d39f99b5", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2860b3daed13da91218675b9a78e3aa2b64d8c8d32d37a8c0f98fb324f691780b1ff2550add7e6470e8647effb79d9890f11c6e8a641eb0ade9f1a7bd898b72d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a66d1105d625a5b83e9684b584926f1f86d51b522008a59f7e06314797e3d958234f90b8fb1e9f959f4c13ac9f71f9d78ebfbf284b14ebec4c6f83691b7b69d"} 03:37:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:37:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:37:10 INFO - Contents: 03:37:10 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a15f732e63ef4069a6fe56041ecd553ebdf7f95ba14cdcbbf90b65afca48c717fa963bddf973cabd4cae8ebfd333e832f45a3669f11ab8916014f18b2e296593", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66eb9978a35e5c8aa9db6478e20e0c21dbf2828e2255ec42dc589f76d9f6fc0d0ddc2f27934c9489e43f4fcfd747b8b409ec250478d8949755df76f3d39f99b5", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2860b3daed13da91218675b9a78e3aa2b64d8c8d32d37a8c0f98fb324f691780b1ff2550add7e6470e8647effb79d9890f11c6e8a641eb0ade9f1a7bd898b72d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a66d1105d625a5b83e9684b584926f1f86d51b522008a59f7e06314797e3d958234f90b8fb1e9f959f4c13ac9f71f9d78ebfbf284b14ebec4c6f83691b7b69d"} 03:37:10 INFO - Running post-run listener: copy_logs_to_upload_dir 03:37:10 INFO - Copying logs to upload dir... 03:37:10 INFO - mkdir: /builds/slave/test/build/upload/logs 03:37:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.949269 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-09-04 03:37:10.279687) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-04 03:37:10.283324) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a15f732e63ef4069a6fe56041ecd553ebdf7f95ba14cdcbbf90b65afca48c717fa963bddf973cabd4cae8ebfd333e832f45a3669f11ab8916014f18b2e296593", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66eb9978a35e5c8aa9db6478e20e0c21dbf2828e2255ec42dc589f76d9f6fc0d0ddc2f27934c9489e43f4fcfd747b8b409ec250478d8949755df76f3d39f99b5", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2860b3daed13da91218675b9a78e3aa2b64d8c8d32d37a8c0f98fb324f691780b1ff2550add7e6470e8647effb79d9890f11c6e8a641eb0ade9f1a7bd898b72d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a66d1105d625a5b83e9684b584926f1f86d51b522008a59f7e06314797e3d958234f90b8fb1e9f959f4c13ac9f71f9d78ebfbf284b14ebec4c6f83691b7b69d"} build_url:https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011344 build_url: 'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a15f732e63ef4069a6fe56041ecd553ebdf7f95ba14cdcbbf90b65afca48c717fa963bddf973cabd4cae8ebfd333e832f45a3669f11ab8916014f18b2e296593", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66eb9978a35e5c8aa9db6478e20e0c21dbf2828e2255ec42dc589f76d9f6fc0d0ddc2f27934c9489e43f4fcfd747b8b409ec250478d8949755df76f3d39f99b5", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2860b3daed13da91218675b9a78e3aa2b64d8c8d32d37a8c0f98fb324f691780b1ff2550add7e6470e8647effb79d9890f11c6e8a641eb0ade9f1a7bd898b72d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a66d1105d625a5b83e9684b584926f1f86d51b522008a59f7e06314797e3d958234f90b8fb1e9f959f4c13ac9f71f9d78ebfbf284b14ebec4c6f83691b7b69d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-04 03:37:10.315110) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:37:10.315435) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0e2a256762fed810e2f0f810000001b3-1504520174.420216-1325879787 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004519 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:37:10.398860) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-04 03:37:10.399168) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-04 03:37:10.399485) ========= ========= Total master_lag: 0.18 =========