builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-049 starttime: 1504635912.38 results: success (0) revision: 973e8b890a62aee4b3170558ac3b608928162ef6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:12.384214) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:12.384615) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:12.384894) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:12.453673) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:12.453969) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-05 11:25:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 91.6M=0s 2017-09-05 11:25:12 (91.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.152339 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:12.646933) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:12.647303) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016088 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:12.701524) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-05 11:25:12.701964) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 973e8b890a62aee4b3170558ac3b608928162ef6 --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 973e8b890a62aee4b3170558ac3b608928162ef6 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-05 11:25:12,671 truncating revision to first 12 chars 2017-09-05 11:25:12,671 Setting DEBUG logging. 2017-09-05 11:25:12,671 attempt 1/10 2017-09-05 11:25:12,671 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/973e8b890a62?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-05 11:25:13,960 unpacking tar archive at: mozilla-central-973e8b890a62/testing/mozharness/ program finished with exit code 0 elapsedTime=1.591715 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-05 11:25:14.309673) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:14.309938) ========= script_repo_revision: 973e8b890a62aee4b3170558ac3b608928162ef6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:14.310274) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:14.310545) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:14.323927) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-09-05 11:25:14.324190) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:25:14 INFO - MultiFileLogger online at 20170905 11:25:14 in /builds/slave/test 11:25:14 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 11:25:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:25:14 INFO - {'append_to_log': False, 11:25:14 INFO - 'base_work_dir': '/builds/slave/test', 11:25:14 INFO - 'blob_upload_branch': 'Firefox', 11:25:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:25:14 INFO - 'branch': 'Firefox', 11:25:14 INFO - 'buildbot_json_path': 'buildprops.json', 11:25:14 INFO - 'code_coverage': False, 11:25:14 INFO - 'config_files': ('talos/linux_config.py',), 11:25:14 INFO - 'default_actions': ('clobber', 11:25:14 INFO - 'read-buildbot-config', 11:25:14 INFO - 'download-and-extract', 11:25:14 INFO - 'populate-webroot', 11:25:14 INFO - 'create-virtualenv', 11:25:14 INFO - 'install', 11:25:14 INFO - 'setup-mitmproxy', 11:25:14 INFO - 'run-tests'), 11:25:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:25:14 INFO - 'disable_ccov_upload': False, 11:25:14 INFO - 'disable_stylo': False, 11:25:14 INFO - 'download_minidump_stackwalk': True, 11:25:14 INFO - 'download_symbols': 'ondemand', 11:25:14 INFO - 'enable_stylo': False, 11:25:14 INFO - 'enable_webrender': False, 11:25:14 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:25:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:25:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:25:14 INFO - 'gecko_profile': False, 11:25:14 INFO - 'gecko_profile_interval': 0, 11:25:14 INFO - 'installer_path': 'installer.exe', 11:25:14 INFO - 'log_level': 'info', 11:25:14 INFO - 'log_name': 'talos', 11:25:14 INFO - 'log_to_console': True, 11:25:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:25:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:25:14 INFO - 'opt_config_files': (), 11:25:14 INFO - 'pip_index': False, 11:25:14 INFO - 'suite': 'dromaeojs-e10s', 11:25:14 INFO - 'system_bits': '32', 11:25:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:25:14 INFO - 'title': 'talos-linux64-ix-049', 11:25:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:25:14 INFO - 'use_talos_json': True, 11:25:14 INFO - 'verify': 'False', 11:25:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:25:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:25:14 INFO - 'work_dir': 'build'} 11:25:14 INFO - [mozharness: 2017-09-05 18:25:14.530146Z] Running clobber step. 11:25:14 INFO - Running pre-action listener: _resource_record_pre_action 11:25:14 INFO - Running main action method: clobber 11:25:14 INFO - rmtree: /builds/slave/test/build 11:25:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:25:15 INFO - Running post-action listener: _resource_record_post_action 11:25:15 INFO - [mozharness: 2017-09-05 18:25:15.500657Z] Finished clobber step (success) 11:25:15 INFO - [mozharness: 2017-09-05 18:25:15.500754Z] Running read-buildbot-config step. 11:25:15 INFO - Running pre-action listener: _resource_record_pre_action 11:25:15 INFO - Running main action method: read_buildbot_config 11:25:15 INFO - Using buildbot properties: 11:25:15 INFO - { 11:25:15 INFO - "project": "", 11:25:15 INFO - "product": "firefox", 11:25:15 INFO - "who": "ffxbld@noreply.mozilla.org", 11:25:15 INFO - "installer_path": "public/build/target.tar.bz2", 11:25:15 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 11:25:15 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:25:15 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 11:25:15 INFO - "stage_platform": "linux64-stylosequential", 11:25:15 INFO - "basedir": "/builds/slave/test", 11:25:15 INFO - "buildnumber": 232, 11:25:15 INFO - "platform": "ubuntu64_hw_styloseq", 11:25:15 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:25:15 INFO - "slavebuilddir": "test", 11:25:15 INFO - "taskId": "YEWu-EF7T9eNrVWOM7h5XA", 11:25:15 INFO - "branch": "mozilla-central", 11:25:15 INFO - "script_repo_revision": "production", 11:25:15 INFO - "revision": "973e8b890a62aee4b3170558ac3b608928162ef6", 11:25:15 INFO - "slavename": "talos-linux64-ix-049", 11:25:15 INFO - "repo_path": "mozilla-central" 11:25:15 INFO - } 11:25:15 INFO - Finding installer, test and symbols from parent task. 11:25:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YEWu-EF7T9eNrVWOM7h5XA'}, attempt #1 11:25:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YEWu-EF7T9eNrVWOM7h5XA'}, attempt #1 11:25:16 INFO - Task dependencies: TJbnw1eAT3m2u_5HiPuYfA 11:25:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA'}, attempt #1 11:25:16 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.tar.bz2 11:25:16 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.test_packages.json 11:25:16 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.crashreporter-symbols.zip 11:25:16 INFO - Running post-action listener: _resource_record_post_action 11:25:16 INFO - [mozharness: 2017-09-05 18:25:16.889963Z] Finished read-buildbot-config step (success) 11:25:16 INFO - [mozharness: 2017-09-05 18:25:16.890182Z] Running download-and-extract step. 11:25:16 INFO - Running pre-action listener: _resource_record_pre_action 11:25:16 INFO - Running main action method: download_and_extract 11:25:16 INFO - mkdir: /builds/slave/test/build/tests 11:25:16 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')]} 11:25:16 INFO - trying https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.test_packages.json 11:25:16 INFO - Downloading https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:25:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:25:17 INFO - Downloaded 989 bytes. 11:25:17 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:25:17 INFO - Using the following test package requirements: 11:25:17 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:25:17 INFO - u'common': [u'target.common.tests.zip'], 11:25:17 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:25:17 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:25:17 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:25:17 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:25:17 INFO - u'mozbase': [u'target.common.tests.zip'], 11:25:17 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:25:17 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:25:17 INFO - u'web-platform': [u'target.common.tests.zip', 11:25:17 INFO - u'target.web-platform.tests.tar.gz'], 11:25:17 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:25:17 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:25:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.common.tests.zip 11:25:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:25:17 INFO - Fetch https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.common.tests.zip into memory 11:25:19 INFO - Content-Length response header: 55022217 11:25:19 INFO - Bytes received: 55022217 11:25:31 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:25:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.common.tests.zip 11:25:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:25:31 INFO - Fetch https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.common.tests.zip into memory 11:25:32 INFO - Content-Length response header: 55022217 11:25:32 INFO - Bytes received: 55022217 11:25:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.talos.tests.zip 11:25:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:25:46 INFO - Fetch https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.talos.tests.zip into memory 11:25:46 INFO - Content-Length response header: 14496650 11:25:46 INFO - Bytes received: 14496650 11:25:46 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')]} 11:25:46 INFO - trying https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.tar.bz2 11:25:46 INFO - Downloading https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:25:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:25:48 INFO - Downloaded 62644193 bytes. 11:25:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.tar.bz2 11:25:48 INFO - mkdir: /builds/slave/test/properties 11:25:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:25:48 INFO - Writing to file /builds/slave/test/properties/build_url 11:25:48 INFO - Contents: 11:25:48 INFO - build_url:https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.tar.bz2 11:25:48 INFO - Running post-action listener: _resource_record_post_action 11:25:48 INFO - Running post-action listener: find_tests_for_verification 11:25:48 INFO - Running post-action listener: set_extra_try_arguments 11:25:48 INFO - [mozharness: 2017-09-05 18:25:48.048575Z] Finished download-and-extract step (success) 11:25:48 INFO - [mozharness: 2017-09-05 18:25:48.048670Z] Running populate-webroot step. 11:25:48 INFO - Running pre-action listener: _resource_record_pre_action 11:25:48 INFO - Running main action method: populate_webroot 11:25:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:25:48 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:25:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:25:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:25:48 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:25:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:25:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:25:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:25:48 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:25:48 INFO - 'talos_options': ['--enable-stylo'], 11:25:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:25:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:25:48 INFO - 'tests': ['damp', 'tps']}, 11:25:48 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:25:48 INFO - 'talos_options': ['--enable-stylo'], 11:25:48 INFO - 'tests': ['damp', 'tps']}, 11:25:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:25:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:25:48 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:25:48 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:25:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:25:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:25:48 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:25:48 INFO - 'talos_options': ['--enable-stylo'], 11:25:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:25:48 INFO - 'other-e10s': {'tests': ['a11yr', 11:25:48 INFO - 'ts_paint', 11:25:48 INFO - 'tpaint', 11:25:48 INFO - 'sessionrestore', 11:25:48 INFO - 'sessionrestore_many_windows', 11:25:48 INFO - 'sessionrestore_no_auto_restore', 11:25:48 INFO - 'tabpaint']}, 11:25:48 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:25:48 INFO - 'tests': ['a11yr', 11:25:48 INFO - 'ts_paint', 11:25:48 INFO - 'tpaint', 11:25:48 INFO - 'sessionrestore', 11:25:48 INFO - 'sessionrestore_many_windows', 11:25:48 INFO - 'sessionrestore_no_auto_restore', 11:25:48 INFO - 'tabpaint']}, 11:25:48 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:25:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:25:48 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:25:48 INFO - 'tests': ['bloom_basic_singleton']}, 11:25:48 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:25:48 INFO - 'tests': ['bloom_basic']}, 11:25:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:25:48 INFO - 'tsvgr_opacity', 11:25:48 INFO - 'tart', 11:25:48 INFO - 'tscrollx', 11:25:48 INFO - 'tsvg_static']}, 11:25:48 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:25:48 INFO - 'tests': ['tsvgx', 11:25:48 INFO - 'tsvgr_opacity', 11:25:48 INFO - 'tart', 11:25:48 INFO - 'tscrollx', 11:25:48 INFO - 'tsvg_static']}, 11:25:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:25:48 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:25:48 INFO - 'talos_options': ['--enable-stylo'], 11:25:48 INFO - 'tests': ['tp5o']}, 11:25:48 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:25:48 INFO - 'talos_options': ['--mitmproxy', 11:25:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:25:48 INFO - '--firstNonBlankPaint'], 11:25:48 INFO - 'tests': ['tp6_google', 11:25:48 INFO - 'tp6_youtube', 11:25:48 INFO - 'tp6_amazon', 11:25:48 INFO - 'tp6_facebook']}, 11:25:48 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:25:48 INFO - 'talos_options': ['--enable-stylo', 11:25:48 INFO - '--mitmproxy', 11:25:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:25:48 INFO - '--firstNonBlankPaint'], 11:25:48 INFO - 'tests': ['tp6_google', 11:25:48 INFO - 'tp6_youtube', 11:25:48 INFO - 'tp6_amazon', 11:25:48 INFO - 'tp6_facebook']}, 11:25:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:25:48 INFO - 'talos_options': ['--enable-stylo', 11:25:48 INFO - '--stylo-threads=1', 11:25:48 INFO - '--mitmproxy', 11:25:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:25:48 INFO - '--firstNonBlankPaint'], 11:25:48 INFO - 'tests': ['tp6_google', 11:25:48 INFO - 'tp6_youtube', 11:25:48 INFO - 'tp6_amazon', 11:25:48 INFO - 'tp6_facebook']}, 11:25:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:25:48 INFO - 'talos_options': ['--xperf_path', 11:25:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:25:48 INFO - 'tests': ['tp5n']}, 11:25:48 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:25:48 INFO - 'talos_options': ['--enable-stylo', 11:25:48 INFO - '--xperf_path', 11:25:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:25:48 INFO - 'tests': ['tp5n']}}} 11:25:48 INFO - Running post-action listener: _resource_record_post_action 11:25:48 INFO - [mozharness: 2017-09-05 18:25:48.062945Z] Finished populate-webroot step (success) 11:25:48 INFO - [mozharness: 2017-09-05 18:25:48.063034Z] Running create-virtualenv step. 11:25:48 INFO - Running pre-action listener: _resource_record_pre_action 11:25:48 INFO - Running main action method: create_virtualenv 11:25:48 INFO - Creating virtualenv /builds/slave/test/build/venv 11:25:48 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:25:48 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:25:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:25:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:25:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:25:48 INFO - Installing distribute.............................................................................................................................................................................................done. 11:25:49 INFO - Installing pip...............done. 11:25:49 INFO - Return code: 0 11:25:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:25:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:25:49 INFO - Reading from file tmpfile_stdout 11:25:49 INFO - Output received: 11:25:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:25:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:25:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12421f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15aea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b2090>, '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 0x15b2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15acf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', '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 11:25:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:25:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:25:49 INFO - Using env: {'DISPLAY': ':0', 11:25:49 INFO - 'HOME': '/home/cltbld', 11:25:49 INFO - 'LANG': 'en_US.UTF-8', 11:25:49 INFO - 'LANGUAGE': 'en_US:en', 11:25:49 INFO - 'LOGNAME': 'cltbld', 11:25:49 INFO - 'MAIL': '/var/mail/cltbld', 11:25:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:49 INFO - 'MOZ_NO_REMOTE': '1', 11:25:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:49 INFO - 'NO_EM_RESTART': '1', 11:25:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:49 INFO - 'PWD': '/builds/slave/test', 11:25:49 INFO - 'SHELL': '/bin/bash', 11:25:49 INFO - 'SHLVL': '1', 11:25:49 INFO - 'TERM': 'linux', 11:25:49 INFO - 'TMOUT': '86400', 11:25:49 INFO - 'USER': 'cltbld', 11:25:49 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', 11:25:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:49 INFO - '_': '/tools/buildbot/bin/python'} 11:25:50 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:25:50 INFO - Downloading/unpacking pip>=1.5 11:25:50 INFO - Running setup.py egg_info for package pip 11:25:50 INFO - warning: no files found matching 'pip/cacert.pem' 11:25:50 INFO - warning: no files found matching '*.html' under directory 'docs' 11:25:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:25:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:25:50 INFO - Installing collected packages: pip 11:25:50 INFO - Found existing installation: pip 1.1 11:25:50 INFO - Uninstalling pip: 11:25:50 INFO - Successfully uninstalled pip 11:25:50 INFO - Running setup.py install for pip 11:25:50 INFO - warning: no files found matching 'pip/cacert.pem' 11:25:50 INFO - warning: no files found matching '*.html' under directory 'docs' 11:25:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:25:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:25:50 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:25:50 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:25:50 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:25:50 INFO - Successfully installed pip 11:25:50 INFO - Cleaning up... 11:25:50 INFO - Return code: 0 11:25:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:25: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')]} 11:25:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25: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')]} 11:25:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12421f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15aea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b2090>, '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 0x15b2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15acf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', '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 11:25:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:25:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:25:50 INFO - Using env: {'DISPLAY': ':0', 11:25:50 INFO - 'HOME': '/home/cltbld', 11:25:50 INFO - 'LANG': 'en_US.UTF-8', 11:25:50 INFO - 'LANGUAGE': 'en_US:en', 11:25:50 INFO - 'LOGNAME': 'cltbld', 11:25:50 INFO - 'MAIL': '/var/mail/cltbld', 11:25:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:50 INFO - 'MOZ_NO_REMOTE': '1', 11:25:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:50 INFO - 'NO_EM_RESTART': '1', 11:25:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:50 INFO - 'PWD': '/builds/slave/test', 11:25:50 INFO - 'SHELL': '/bin/bash', 11:25:50 INFO - 'SHLVL': '1', 11:25:50 INFO - 'TERM': 'linux', 11:25:50 INFO - 'TMOUT': '86400', 11:25:50 INFO - 'USER': 'cltbld', 11:25:50 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', 11:25:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:50 INFO - '_': '/tools/buildbot/bin/python'} 11:25:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:50 INFO - Downloading/unpacking psutil>=3.1.1 11:25:50 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 11:25:50 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 11:25:50 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 11:25:50 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 11:25:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:25:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:25:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:25:52 INFO - Installing collected packages: psutil 11:25:52 INFO - Running setup.py install for psutil 11:25:52 INFO - building 'psutil._psutil_linux' extension 11:25:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 11:25:53 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 11:25:53 INFO - building 'psutil._psutil_posix' extension 11:25:53 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 11:25:53 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 11:25:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:25:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:25:53 INFO - Successfully installed psutil 11:25:53 INFO - Cleaning up... 11:25:53 INFO - Return code: 0 11:25:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:25:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:53 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 0x12421f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15aea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b2090>, '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 0x15b2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15acf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', '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 11:25:53 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 11:25:53 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 11:25:53 INFO - Using env: {'DISPLAY': ':0', 11:25:53 INFO - 'HOME': '/home/cltbld', 11:25:53 INFO - 'LANG': 'en_US.UTF-8', 11:25:53 INFO - 'LANGUAGE': 'en_US:en', 11:25:53 INFO - 'LOGNAME': 'cltbld', 11:25:53 INFO - 'MAIL': '/var/mail/cltbld', 11:25:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:53 INFO - 'MOZ_NO_REMOTE': '1', 11:25:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:53 INFO - 'NO_EM_RESTART': '1', 11:25:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:53 INFO - 'PWD': '/builds/slave/test', 11:25:53 INFO - 'SHELL': '/bin/bash', 11:25:53 INFO - 'SHLVL': '1', 11:25:53 INFO - 'TERM': 'linux', 11:25:53 INFO - 'TMOUT': '86400', 11:25:53 INFO - 'USER': 'cltbld', 11:25:53 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', 11:25:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:53 INFO - '_': '/tools/buildbot/bin/python'} 11:25:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:25: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 11:25: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 11:25: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 11:25: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 11:25:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:25:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:25:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:25:55 INFO - Installing collected packages: mozsystemmonitor 11:25:55 INFO - Running setup.py install for mozsystemmonitor 11:25:55 INFO - Successfully installed mozsystemmonitor 11:25:55 INFO - Cleaning up... 11:25:55 INFO - Return code: 0 11:25:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:25:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12421f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15aea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b2090>, '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 0x15b2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15acf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', '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 11:25:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:25:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:25:55 INFO - Using env: {'DISPLAY': ':0', 11:25:55 INFO - 'HOME': '/home/cltbld', 11:25:55 INFO - 'LANG': 'en_US.UTF-8', 11:25:55 INFO - 'LANGUAGE': 'en_US:en', 11:25:55 INFO - 'LOGNAME': 'cltbld', 11:25:55 INFO - 'MAIL': '/var/mail/cltbld', 11:25:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:55 INFO - 'MOZ_NO_REMOTE': '1', 11:25:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:55 INFO - 'NO_EM_RESTART': '1', 11:25:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:55 INFO - 'PWD': '/builds/slave/test', 11:25:55 INFO - 'SHELL': '/bin/bash', 11:25:55 INFO - 'SHLVL': '1', 11:25:55 INFO - 'TERM': 'linux', 11:25:55 INFO - 'TMOUT': '86400', 11:25:55 INFO - 'USER': 'cltbld', 11:25:55 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', 11:25:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:55 INFO - '_': '/tools/buildbot/bin/python'} 11:25:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:55 INFO - Downloading/unpacking jsonschema==2.5.1 11:25: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 11:25: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 11:25: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 11:25: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 11:25:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:25:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:25:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:25:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:25:56 INFO - Downloading functools32-3.2.3-2.tar.gz 11:25:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:25:56 INFO - warning: no files found matching '*.txt' 11:25:56 INFO - no previously-included directories found matching 'build' 11:25:56 INFO - no previously-included directories found matching 'dist' 11:25:56 INFO - no previously-included directories found matching '.git*' 11:25:56 INFO - Installing collected packages: jsonschema, functools32 11:25:56 INFO - Running setup.py install for functools32 11:25:56 INFO - warning: no files found matching '*.txt' 11:25:56 INFO - no previously-included directories found matching 'build' 11:25:56 INFO - no previously-included directories found matching 'dist' 11:25:56 INFO - no previously-included directories found matching '.git*' 11:25:56 INFO - Successfully installed jsonschema functools32 11:25:56 INFO - Cleaning up... 11:25:56 INFO - Return code: 0 11:25:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:25: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')]} 11:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25: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')]} 11:25:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12421f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15aea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b2090>, '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 0x15b2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15acf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', '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 11:25:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:25:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:25:56 INFO - Using env: {'DISPLAY': ':0', 11:25:56 INFO - 'HOME': '/home/cltbld', 11:25:56 INFO - 'LANG': 'en_US.UTF-8', 11:25:56 INFO - 'LANGUAGE': 'en_US:en', 11:25:56 INFO - 'LOGNAME': 'cltbld', 11:25:56 INFO - 'MAIL': '/var/mail/cltbld', 11:25:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:56 INFO - 'MOZ_NO_REMOTE': '1', 11:25:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:56 INFO - 'NO_EM_RESTART': '1', 11:25:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:56 INFO - 'PWD': '/builds/slave/test', 11:25:56 INFO - 'SHELL': '/bin/bash', 11:25:56 INFO - 'SHLVL': '1', 11:25:56 INFO - 'TERM': 'linux', 11:25:56 INFO - 'TMOUT': '86400', 11:25:56 INFO - 'USER': 'cltbld', 11:25:56 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', 11:25:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:56 INFO - '_': '/tools/buildbot/bin/python'} 11:25:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:25:57 INFO - Cleaning up... 11:25:57 INFO - Return code: 0 11:25:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:25:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:57 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 0x12421f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15aea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b2090>, '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 0x15b2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15acf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', '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 11:25:57 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 11:25:57 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 11:25:57 INFO - Using env: {'DISPLAY': ':0', 11:25:57 INFO - 'HOME': '/home/cltbld', 11:25:57 INFO - 'LANG': 'en_US.UTF-8', 11:25:57 INFO - 'LANGUAGE': 'en_US:en', 11:25:57 INFO - 'LOGNAME': 'cltbld', 11:25:57 INFO - 'MAIL': '/var/mail/cltbld', 11:25:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:57 INFO - 'MOZ_NO_REMOTE': '1', 11:25:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:57 INFO - 'NO_EM_RESTART': '1', 11:25:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:57 INFO - 'PWD': '/builds/slave/test', 11:25:57 INFO - 'SHELL': '/bin/bash', 11:25:57 INFO - 'SHLVL': '1', 11:25:57 INFO - 'TERM': 'linux', 11:25:57 INFO - 'TMOUT': '86400', 11:25:57 INFO - 'USER': 'cltbld', 11:25:57 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', 11:25:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:57 INFO - '_': '/tools/buildbot/bin/python'} 11:25:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:57 INFO - Downloading/unpacking blobuploader==1.2.4 11:25:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:25:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:25:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:25:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:25:58 INFO - Downloading blobuploader-1.2.4.tar.gz 11:25:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:25:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:25:58 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 11:25:58 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 11:25:58 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 11:25:58 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 11:25:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:25:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:25:58 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 11:25:58 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 11:25:58 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 11:25:58 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 11:25:58 INFO - Downloading docopt-0.6.1.tar.gz 11:25:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:25:59 INFO - Installing collected packages: blobuploader, requests, docopt 11:25:59 INFO - Running setup.py install for blobuploader 11:25:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:25:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:25:59 INFO - Running setup.py install for requests 11:25:59 INFO - Running setup.py install for docopt 11:25:59 INFO - Successfully installed blobuploader requests docopt 11:25:59 INFO - Cleaning up... 11:25:59 INFO - Return code: 0 11:25:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:25:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:59 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 0x12421f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15aea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b2090>, '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 0x15b2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15acf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', '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 11:25:59 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 11:25:59 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 11:25:59 INFO - Using env: {'DISPLAY': ':0', 11:25:59 INFO - 'HOME': '/home/cltbld', 11:25:59 INFO - 'LANG': 'en_US.UTF-8', 11:25:59 INFO - 'LANGUAGE': 'en_US:en', 11:25:59 INFO - 'LOGNAME': 'cltbld', 11:25:59 INFO - 'MAIL': '/var/mail/cltbld', 11:25:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:59 INFO - 'MOZ_NO_REMOTE': '1', 11:25:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:59 INFO - 'NO_EM_RESTART': '1', 11:25:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:59 INFO - 'PWD': '/builds/slave/test', 11:25:59 INFO - 'SHELL': '/bin/bash', 11:25:59 INFO - 'SHLVL': '1', 11:25:59 INFO - 'TERM': 'linux', 11:25:59 INFO - 'TMOUT': '86400', 11:25:59 INFO - 'USER': 'cltbld', 11:25:59 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', 11:25:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:59 INFO - '_': '/tools/buildbot/bin/python'} 11:25:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:25:59 INFO - Running setup.py (path:/tmp/pip-untjXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:25:59 INFO - Running setup.py (path:/tmp/pip-qxdKU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:26:00 INFO - Running setup.py (path:/tmp/pip-NESjb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:26:00 INFO - Running setup.py (path:/tmp/pip-cXA0nm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:26:00 INFO - Running setup.py (path:/tmp/pip-kzRwWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:26:00 INFO - Running setup.py (path:/tmp/pip-WSxZbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:26:00 INFO - Running setup.py (path:/tmp/pip-KCnYyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:26:00 INFO - Running setup.py (path:/tmp/pip-iy4dkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:26:00 INFO - Running setup.py (path:/tmp/pip-kh58tE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:26:00 INFO - Running setup.py (path:/tmp/pip-abo2WN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:26:00 INFO - Running setup.py (path:/tmp/pip-7PwrdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:26:00 INFO - Running setup.py (path:/tmp/pip-ZgbWux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:26:00 INFO - Running setup.py (path:/tmp/pip-CKENmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:26:00 INFO - Running setup.py (path:/tmp/pip-aO6AGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:00 INFO - Running setup.py (path:/tmp/pip-tD0fxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:26:00 INFO - Running setup.py (path:/tmp/pip-wsZiXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:26:00 INFO - Running setup.py (path:/tmp/pip-etFUzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:26:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:26:01 INFO - Running setup.py install for manifestparser 11:26:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:26:01 INFO - Running setup.py install for mozcrash 11:26:01 INFO - Running setup.py install for mozdebug 11:26:01 INFO - Running setup.py install for mozdevice 11:26:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:26:01 INFO - Running setup.py install for mozfile 11:26:01 INFO - Running setup.py install for mozhttpd 11:26:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:26:01 INFO - Running setup.py install for mozinfo 11:26:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:26:01 INFO - Running setup.py install for mozInstall 11:26:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:26:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:26:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:26:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:26:01 INFO - Running setup.py install for mozleak 11:26:01 INFO - Running setup.py install for mozlog 11:26:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:26:02 INFO - Running setup.py install for moznetwork 11:26:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:26:02 INFO - Running setup.py install for mozprocess 11:26:02 INFO - Running setup.py install for mozprofile 11:26:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:26:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:26:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:26:02 INFO - Running setup.py install for mozrunner 11:26:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:26:02 INFO - Running setup.py install for mozscreenshot 11:26:02 INFO - Running setup.py install for moztest 11:26:02 INFO - Running setup.py install for mozversion 11:26:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:26:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:26:02 INFO - Cleaning up... 11:26:02 INFO - Return code: 0 11:26:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:26: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')]} 11:26:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26: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')]} 11:26:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:02 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 0x12421f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15aea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b2090>, '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 0x15b2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15acf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', '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 11:26:02 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 11:26:02 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 11:26:02 INFO - Using env: {'DISPLAY': ':0', 11:26:02 INFO - 'HOME': '/home/cltbld', 11:26:02 INFO - 'LANG': 'en_US.UTF-8', 11:26:02 INFO - 'LANGUAGE': 'en_US:en', 11:26:02 INFO - 'LOGNAME': 'cltbld', 11:26:02 INFO - 'MAIL': '/var/mail/cltbld', 11:26:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:02 INFO - 'MOZ_NO_REMOTE': '1', 11:26:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:02 INFO - 'NO_EM_RESTART': '1', 11:26:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:02 INFO - 'PWD': '/builds/slave/test', 11:26:02 INFO - 'SHELL': '/bin/bash', 11:26:02 INFO - 'SHLVL': '1', 11:26:02 INFO - 'TERM': 'linux', 11:26:02 INFO - 'TMOUT': '86400', 11:26:02 INFO - 'USER': 'cltbld', 11:26:02 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', 11:26:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:02 INFO - '_': '/tools/buildbot/bin/python'} 11:26:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:26:03 INFO - Running setup.py (path:/tmp/pip-Zo_o06-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:26:03 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)) 11:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:26:03 INFO - Running setup.py (path:/tmp/pip-1FLgCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:26:03 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)) 11:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:26:03 INFO - Running setup.py (path:/tmp/pip-TpUPoz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:26:03 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)) 11:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:26:03 INFO - Running setup.py (path:/tmp/pip-wC8mZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:26:03 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)) 11:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:26:03 INFO - Running setup.py (path:/tmp/pip-UnuRil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:26:03 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)) 11:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:26:03 INFO - Running setup.py (path:/tmp/pip-s8alVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:26:03 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)) 11:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:26:03 INFO - Running setup.py (path:/tmp/pip-oei_SN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:26:03 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)) 11:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:26:03 INFO - Running setup.py (path:/tmp/pip-VbAv6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:26:03 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)) 11:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:26:03 INFO - Running setup.py (path:/tmp/pip-NmgUC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:26:03 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)) 11:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:26:03 INFO - Running setup.py (path:/tmp/pip-zaXFJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:26:03 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)) 11:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:26:03 INFO - Running setup.py (path:/tmp/pip-GeMWej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:26:03 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)) 11:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:26:03 INFO - Running setup.py (path:/tmp/pip-gXOak5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:26:03 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)) 11:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:26:03 INFO - Running setup.py (path:/tmp/pip-RdVs9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:26:03 INFO - Running setup.py (path:/tmp/pip-fgTwjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:26:04 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)) 11:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:04 INFO - Running setup.py (path:/tmp/pip-ey9PG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:04 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)) 11:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:26:04 INFO - Running setup.py (path:/tmp/pip-jmyqSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:26:04 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)) 11:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:26:04 INFO - Running setup.py (path:/tmp/pip-0kCSNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:26:04 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)) 11:26:04 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)) 11:26:04 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)) 11:26:04 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)) 11:26:04 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)) 11:26:04 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)) 11:26:04 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)) 11:26:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:26:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:05 INFO - Downloading blessings-1.6.tar.gz 11:26:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:26:05 INFO - Installing collected packages: blessings 11:26:05 INFO - Running setup.py install for blessings 11:26:05 INFO - Successfully installed blessings 11:26:05 INFO - Cleaning up... 11:26:05 INFO - Return code: 0 11:26:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:26:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:26:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:26:05 INFO - Reading from file tmpfile_stdout 11:26:05 INFO - Current package versions: 11:26:05 INFO - argparse == 1.2.1 11:26:05 INFO - blessings == 1.6 11:26:05 INFO - blobuploader == 1.2.4 11:26:05 INFO - docopt == 0.6.1 11:26:05 INFO - functools32 == 3.2.3-2 11:26:05 INFO - jsonschema == 2.5.1 11:26:05 INFO - manifestparser == 1.1 11:26:05 INFO - mozInstall == 1.13 11:26:05 INFO - mozcrash == 1.0 11:26:05 INFO - mozdebug == 0.1 11:26:05 INFO - mozdevice == 0.51 11:26:05 INFO - mozfile == 1.2 11:26:05 INFO - mozhttpd == 0.7 11:26:05 INFO - mozinfo == 0.10 11:26:05 INFO - mozleak == 0.1 11:26:05 INFO - mozlog == 3.5 11:26:05 INFO - moznetwork == 0.27 11:26:05 INFO - mozprocess == 0.25 11:26:05 INFO - mozprofile == 0.28 11:26:05 INFO - mozrunner == 6.13 11:26:05 INFO - mozscreenshot == 0.1 11:26:05 INFO - mozsystemmonitor == 0.3 11:26:05 INFO - moztest == 0.8 11:26:05 INFO - mozversion == 1.4 11:26:05 INFO - psutil == 3.1.1 11:26:05 INFO - requests == 1.2.3 11:26:05 INFO - wsgiref == 0.1.2 11:26:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:26: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')]} 11:26:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26: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')]} 11:26:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:05 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 0x12421f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15aea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b2090>, '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 0x15b2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15acf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', '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 11:26:05 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 11:26:05 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 11:26:05 INFO - Using env: {'DISPLAY': ':0', 11:26:05 INFO - 'HOME': '/home/cltbld', 11:26:05 INFO - 'LANG': 'en_US.UTF-8', 11:26:05 INFO - 'LANGUAGE': 'en_US:en', 11:26:05 INFO - 'LOGNAME': 'cltbld', 11:26:05 INFO - 'MAIL': '/var/mail/cltbld', 11:26:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:05 INFO - 'MOZ_NO_REMOTE': '1', 11:26:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:05 INFO - 'NO_EM_RESTART': '1', 11:26:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:05 INFO - 'PWD': '/builds/slave/test', 11:26:05 INFO - 'SHELL': '/bin/bash', 11:26:05 INFO - 'SHLVL': '1', 11:26:05 INFO - 'TERM': 'linux', 11:26:05 INFO - 'TMOUT': '86400', 11:26:05 INFO - 'USER': 'cltbld', 11:26:05 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', 11:26:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:05 INFO - '_': '/tools/buildbot/bin/python'} 11:26:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:06 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)) 11:26:06 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)) 11:26:06 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)) 11:26:06 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)) 11:26:06 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)) 11:26:06 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)) 11:26:06 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)) 11:26:06 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)) 11:26:06 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)) 11:26:06 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:26:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:26:07 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)) 11:26:07 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)) 11:26:07 INFO - Installing collected packages: simplejson 11:26:07 INFO - Running setup.py install for simplejson 11:26:07 INFO - building 'simplejson._speedups' extension 11:26:07 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 11:26:08 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 11:26:08 INFO - Successfully installed simplejson 11:26:08 INFO - Cleaning up... 11:26:08 INFO - Return code: 0 11:26:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:26:08 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')]} 11:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:08 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')]} 11:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:08 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 0x12421f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15aea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b2090>, '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 0x15b2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15acf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', '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 11:26:08 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 11:26:08 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 11:26:08 INFO - Using env: {'DISPLAY': ':0', 11:26:08 INFO - 'HOME': '/home/cltbld', 11:26:08 INFO - 'LANG': 'en_US.UTF-8', 11:26:08 INFO - 'LANGUAGE': 'en_US:en', 11:26:08 INFO - 'LOGNAME': 'cltbld', 11:26:08 INFO - 'MAIL': '/var/mail/cltbld', 11:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:08 INFO - 'MOZ_NO_REMOTE': '1', 11:26:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:08 INFO - 'NO_EM_RESTART': '1', 11:26:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:08 INFO - 'PWD': '/builds/slave/test', 11:26:08 INFO - 'SHELL': '/bin/bash', 11:26:08 INFO - 'SHLVL': '1', 11:26:08 INFO - 'TERM': 'linux', 11:26:08 INFO - 'TMOUT': '86400', 11:26:08 INFO - 'USER': 'cltbld', 11:26:08 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', 11:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:08 INFO - '_': '/tools/buildbot/bin/python'} 11:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:26:08 INFO - Cleaning up... 11:26:08 INFO - Return code: 0 11:26:08 INFO - Running post-action listener: _resource_record_post_action 11:26:08 INFO - Running post-action listener: _start_resource_monitoring 11:26:08 INFO - Starting resource monitoring. 11:26:08 INFO - [mozharness: 2017-09-05 18:26:08.526483Z] Finished create-virtualenv step (success) 11:26:08 INFO - [mozharness: 2017-09-05 18:26:08.527486Z] Running install step. 11:26:08 INFO - Running pre-action listener: _resource_record_pre_action 11:26:08 INFO - Running main action method: install 11:26:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:26:08 INFO - Reading from file tmpfile_stdout 11:26:08 INFO - Detecting whether we're running mozinstall >=1.0... 11:26:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:26:08 INFO - Reading from file tmpfile_stdout 11:26:08 INFO - Output received: 11:26:08 INFO - Usage: mozinstall [options] installer 11:26:08 INFO - Options: 11:26:08 INFO - -h, --help show this help message and exit 11:26:08 INFO - -d DEST, --destination=DEST 11:26:08 INFO - Directory to install application into. [default: 11:26:08 INFO - "/builds/slave/test"] 11:26:08 INFO - --app=APP Application being installed. [default: firefox] 11:26:08 INFO - mkdir: /builds/slave/test/build/application 11:26:08 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'] 11:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:26:25 INFO - Reading from file tmpfile_stdout 11:26:25 INFO - Output received: 11:26:25 INFO - /builds/slave/test/build/application/firefox/firefox 11:26:25 INFO - Running post-action listener: _resource_record_post_action 11:26:25 INFO - [mozharness: 2017-09-05 18:26:25.391286Z] Finished install step (success) 11:26:25 INFO - [mozharness: 2017-09-05 18:26:25.391539Z] Running setup-mitmproxy step. 11:26:25 INFO - Running pre-action listener: _resource_record_pre_action 11:26:25 INFO - Running main action method: setup_mitmproxy 11:26:25 INFO - Skipping: mitmproxy is not required 11:26:25 INFO - Running post-action listener: _resource_record_post_action 11:26:25 INFO - [mozharness: 2017-09-05 18:26:25.392765Z] Finished setup-mitmproxy step (success) 11:26:25 INFO - [mozharness: 2017-09-05 18:26:25.392985Z] Running run-tests step. 11:26:25 INFO - Running pre-action listener: _resource_record_pre_action 11:26:25 INFO - Running pre-action listener: _set_gcov_prefix 11:26:25 INFO - Running main action method: run_tests 11:26:25 WARNING - Try message not found. 11:26:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:26:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:26:25 INFO - Python 2.7.3 11:26:25 INFO - Return code: 0 11:26:25 INFO - grabbing minidump binary from tooltool 11:26:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:25 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 0x15b2090>, '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 0x15b2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15acf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:26:25 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 11:26:25 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 11:26:25 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 11:26:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:26:25 INFO - Return code: 0 11:26:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:26:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:26:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:26:25 INFO - ENV: RUST_BACKTRACE is now full 11:26:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:26:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:26:25 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/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:26:25 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/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-049 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:26:25 INFO - Using env: {'DISPLAY': ':0', 11:26:25 INFO - 'HOME': '/home/cltbld', 11:26:25 INFO - 'LANG': 'en_US.UTF-8', 11:26:25 INFO - 'LANGUAGE': 'en_US:en', 11:26:25 INFO - 'LOGNAME': 'cltbld', 11:26:25 INFO - 'MAIL': '/var/mail/cltbld', 11:26:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:26:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:26:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:25 INFO - 'MOZ_NO_REMOTE': '1', 11:26:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:26:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:25 INFO - 'NO_EM_RESTART': '1', 11:26:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:25 INFO - 'PWD': '/builds/slave/test', 11:26:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:26:25 INFO - 'RUST_BACKTRACE': 'full', 11:26:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:26:25 INFO - 'SHELL': '/bin/bash', 11:26:25 INFO - 'SHLVL': '1', 11:26:25 INFO - 'STYLO_FORCE_ENABLED': '1', 11:26:25 INFO - 'STYLO_THREADS': '1', 11:26:25 INFO - 'TERM': 'linux', 11:26:25 INFO - 'TMOUT': '86400', 11:26:25 INFO - 'USER': 'cltbld', 11:26:25 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', 11:26:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:25 INFO - '_': '/tools/buildbot/bin/python'} 11:26:25 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/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:26:25 INFO - mozversion application_buildid: 20170905173605 11:26:25 INFO - mozversion application_changeset: 973e8b890a62aee4b3170558ac3b608928162ef6 11:26:25 INFO - mozversion application_display_name: Nightly 11:26:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:26:25 INFO - mozversion application_name: Firefox 11:26:25 INFO - mozversion application_remotingname: firefox 11:26:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:26:25 INFO - mozversion application_vendor: Mozilla 11:26:25 INFO - mozversion application_version: 57.0a1 11:26:25 INFO - mozversion platform_buildid: 20170905173605 11:26:25 INFO - mozversion platform_changeset: 973e8b890a62aee4b3170558ac3b608928162ef6 11:26:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:26:25 INFO - mozversion platform_version: 57.0a1 11:26:25 INFO - using testdate: 1504635985 11:26:25 INFO - actual date: 1504635985 11:26:25 INFO - starting webserver on 'localhost:46051' 11:26:25 INFO - SUITE-START | Running 2 tests 11:26:25 INFO - TEST-START | dromaeo_css 11:26:25 INFO - Initialising browser for dromaeo_css test... 11:26:25 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46051/getInfo.html -profile /tmp/tmp1DfiNX/profile 11:26:25 INFO - TEST-INFO | started process 3514 (/builds/slave/test/build/application/firefox/firefox http://localhost:46051/getInfo.html) 11:26:32 INFO - TEST-INFO | 3514: exit 0 11:26:32 INFO - Browser initialized. 11:26:32 INFO - Running cycle 1/1 for dromaeo_css test... 11:26:32 INFO - Using env: {'DISPLAY': ':0', 11:26:32 INFO - 'HOME': '/home/cltbld', 11:26:32 INFO - 'JSGC_DISABLE_POISONING': '1', 11:26:32 INFO - 'LANG': 'en_US.UTF-8', 11:26:32 INFO - 'LANGUAGE': 'en_US:en', 11:26:32 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:26:32 INFO - 'LOGNAME': 'cltbld', 11:26:32 INFO - 'MAIL': '/var/mail/cltbld', 11:26:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:26:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:26:32 INFO - 'MOZ_CRASHREPORTER': '1', 11:26:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:32 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:26:32 INFO - 'MOZ_NO_REMOTE': '1', 11:26:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:26:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:32 INFO - 'NO_EM_RESTART': '1', 11:26:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:32 INFO - 'PWD': '/builds/slave/test', 11:26:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:26:32 INFO - 'RUST_BACKTRACE': 'full', 11:26:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:26:32 INFO - 'SHELL': '/bin/bash', 11:26:32 INFO - 'SHLVL': '1', 11:26:32 INFO - 'STYLO_FORCE_ENABLED': '1', 11:26:32 INFO - 'STYLO_THREADS': '1', 11:26:32 INFO - 'TERM': 'linux', 11:26:32 INFO - 'TMOUT': '86400', 11:26:32 INFO - 'USER': 'cltbld', 11:26:32 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', 11:26:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:32 INFO - '_': '/tools/buildbot/bin/python'} 11:26:32 INFO - TEST-INFO | started process 3699 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1DfiNX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:26:33 INFO - PID 3699 | 11:26:33 INFO - PID 3699 | (/builds/slave/test/build/application/firefox/firefox:3751): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:26:33 INFO - PID 3699 | 11:26:43 INFO - PID 3699 | RSS: Main: 167100416 11:26:43 INFO - PID 3699 | 11:29:43 INFO - PID 3699 | Cycle 1(1): loaded http://localhost:46051/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46051/tests/dromaeo/cssquery-ext.html) 11:29:44 INFO - PID 3699 | RSS: Main: 173887488 11:29:44 INFO - PID 3699 | 11:32:46 INFO - PID 3699 | Cycle 1(1): loaded http://localhost:46051/tests/dromaeo/cssquery-ext.html (next: http://localhost:46051/tests/dromaeo/cssquery-jquery.html) 11:32:46 INFO - PID 3699 | RSS: Main: 170373120 11:32:46 INFO - PID 3699 | 11:35:48 INFO - PID 3699 | Cycle 1(1): loaded http://localhost:46051/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46051/tests/dromaeo/cssquery-mootools.html) 11:35:49 INFO - PID 3699 | RSS: Main: 172871680 11:35:49 INFO - PID 3699 | 11:38:50 INFO - PID 3699 | Cycle 1(1): loaded http://localhost:46051/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46051/tests/dromaeo/cssquery-prototype.html) 11:38:51 INFO - PID 3699 | RSS: Main: 171204608 11:38:51 INFO - PID 3699 | 11:41:55 INFO - PID 3699 | Cycle 1(1): loaded http://localhost:46051/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46051/tests/dromaeo/cssquery-yui.html) 11:41:55 INFO - PID 3699 | RSS: Main: 175263744 11:41:55 INFO - PID 3699 | 11:44:57 INFO - PID 3699 | Cycle 1(1): loaded http://localhost:46051/tests/dromaeo/cssquery-yui.html (next: http://localhost:46051/tests/dromaeo/cssquery-dojo.html) 11:44:57 INFO - PID 3699 | RSS: Main: 175693824 11:44:57 INFO - PID 3699 | 11:44:57 INFO - PID 3699 | __start_tp_report 11:44:57 INFO - PID 3699 | _x_x_mozilla_page_load 11:44:57 INFO - PID 3699 | _x_x_mozilla_page_load_details 11:44:57 INFO - PID 3699 | |i|pagename|runs| 11:44:57 INFO - PID 3699 | |0;dojo.html;3318.8838390656333;3363.5627368442097;3372.376110419571;3380.7633465657404;3398.371301309258;6347.047942808581;6459.838504037401;6461.967690161543;6467.094606755047;6569.277379488252;6494.123293355396;6558.114654521638;6581.210254769434;6583.440407565363;6593.241777195624;5907.379725128864;5928.7628494860155;5979.641221526712;6010.338862725097;6076.635401875879;5883.17635531026;5893.351731309553;5955.553333499983;5967.701614919259;5969.194158788551;5867.178594996704;5957.553183511254;5959.672218028007;5982.192404025444;5985.910211346833;5898.616589921684;5919.3192782829665;5965.164876917235;5975.9701201494145;5995.130706047598;5742.856428589277;5863.882722345529;5897.852553686149;5938.317093534256;5945.8810823783715;6363.9045414318825;6392.968035159794;6415.1981002374705;6415.582987105823;6451.322611125811;6968.651567421659;6996.090508233948;7029.648517574101;7035.859282814366;7039.472039597;6965.756198533078;6982.476314276399;6987.056747339095;7046.7533636323;7054.329838665319;6326.936730632727;6399.584027038228;6469.223693156851;6499.707513161919;6589.769358072444;7175;7238.384737297389;7253.963730181315;7255.09311336083;7276.745313913988;6859.2454829968665;6879.58722476643;6929.237783843773;6934.618595977169;6953.756618518429;6947.270536593686;6958.686859091353;7037.944308353685;7064.576125432491;7092.25531319204;6701.128853249046;6762.661866906635;6810.897836532456;6910.930890691029;7008.614526201108;6926.341997510229;6935.861282774316;6939.444844412435;6945.340192681687;6950.860982780316;5444.40111587725;5463.644863083966;5506.339239291311;5516.117421212587;5533.584981126432;4746.38297021385;4773.164696178155;4808.495108013608;4825.10735513931;4831.589314908202;5918.763249470059;6024.819255422344;6038.969805150946;6102.877942441215;6116.143739876421;6898.655067246617;6899.827504312432;6900.275471075565;6909.792706218822;6910.170779506491;5866.589338746332;5968.582199246098;5981.850453738692;5982.0727787193055;5999.310079340845;5912.940870591239;5918.289805223315;5918.585699001029;5932.199153114274;5932.822015339547;6637.900431493532;6654.800355989328;6668.599884006975;6676.165479315086;6782.118324617768;6978.232394436506;6980.930190698028;7040.331168539083;7046.189688185924;7061.752838650623;6565.671716414065;6645.60126392418;6646.4350530205475;6647.135872336566;6681.5656982296;6849.794506164823;6892.724291028303;6945.305469453015;6979.790606281819;7003.6148011857795;6806.863862722619;6902.033715279966;6951.930480695129;6985.9301406987315;7026.508144430045;6814.386705196556;6833.521653484408;6849.212340580698;6850.691718872563;6859.176898772179;6744.966275168593;6774.288699686659;6777.728890844311;6790.558613690095;6792.218894827158;6636.070950067091;6642.169728783902;6643.136392269167;6652.201735791736;6732.629705366252;6642.900356494753;6682.565633233824;6732.596044237362;6830.59016459014;6846;6528.640924749185;6529.608223506605;6553.148090748172;6556.213254409501;6558.508111891531 11:44:57 INFO - PID 3699 | |1;ext.html;826.0912995704725;868.8436081505329;893.2273583350404;896.4038914122107;1160.1588848085134;30736;31269.061928142164;41073.3838992415;42228.788856055755;42416;42476.36285455721;42915.14169716604;42944.57055429445;43081.78459107708;43145.35281970765;7667.386609071261;7689;7801.414893882953;7803.8049048773955;7829.295363417291;7682.500637458547;7753.457257991915;7776.961115194416;7779.961100194491;7788.6105694715325;7717.18969508202;7776.183500732426;7780.338671212954;7787;7789.727359542417;7734.1492435832015;7748.767536973844;7764.611769411564;7776.416768742339;7785.922140778604;7679.654415551314;7715.729949451742;7716.6913323467;7737.187595302468;7764.301212890867;36571.26857462862;36588.26823463543;36900.446493302625;36952.26095478102;36968;3544.6632569906074;3545.149164200599;3555.484454754072;3571.2857428514403;3574.374484465234;30960.845195773876;31085;31189.37621247585;31287.530687039714;31319.843400783077;4993.375828021497;5176.249443830623;5202.08963431398;5210.557102646279;5229.08491014071;179381;180572;181844;181940;182104;47058;47100.5289947103;47177.76411117991;47183.292250616265;47374.763126184145;49403.50596494061;49448;49448.25827612517;49463.505364946606;49627.75186124119;14309;16007;16229.67540649204;16240.18799060066;16254.75617865733;12001.459934302977;13085.345732713517;13307.201567905957;13316.400761965735;13355.599332020054;13352;13363.26502042397;13416.664583385493;13430.328483575782;13512.662183445493;5524.779008839601;5576;5648.124540696199;5682.431756824367;5717.456841600041;1005.8591797148405;1023.5240613114788;1026.440589878518;1036.4144258493927;1042.0985847242082;22275.48977013387;22366.776332236794;22391;22439.21462748796;22635.20776772805;1115.4924509348227;1126.594426006637;1135.0522313868012;1135.28477059452;1135.8125909224884;11836.171467997157;11884.702882428008;11899.762004759856;11943.223690460092;11964.940175299067;217312;218101;218414;219560;220028;52208;52558.21162682563;55885.161722574194;59151;59478.40521594815;1479.2677624575904;1503.8721708654668;1518.096732444216;1529.2812378182236;1531.035447667962;27527.449451011267;27554.311142221606;27603.86198068997;27682.584761229;27691.72308276972;50459;50844.49155508546;50950.490495096055;51293.7435312821;51425.48574514357;199308;200865;201049;201138;201983;35596.64403356037;35922.28155436929;36028;37927.81036094802;37973.43039854349;44919.1016179668;45362.546374535836;45592.54407455883;45641.543584563726;45745.941432636275;34122.829385853904;34451;34551.30897382089;34819;35274.470882936264;33815.492767609;33828;33893.3221335567;34212.31575368528;34631.82684086564;37538.81230593829;37917.05207369838;38050.80974595109;38070.809645951595;38081.4287785689;32903.506447402826;33290;33383.83308083444;33422;33946.49080263748;23759.643605345587;24066;24099.156529521384;24646.876765616056;25001.499970000135 11:44:57 INFO - PID 3699 | |2;jquery.html;563;568.3974986514295;571.7827225654254;571.9399463056379;583.3845293215658;21895.12419503218;22611;22619.660705089416;22643.886780566074;22713.205037823638;23856.40358991026;25895.74104258957;25919.48161036778;26022.349441263977;26098.608520872156;6656.068150458927;6797.490188235877;6799.422049125806;6801.353871382235;6802.4217941475035;6401.679916004205;6699.598024118544;6702.932970670304;6703.798886033427;6735.932640673604;6655.866882662343;6662.034005069262;6667.666616669171;6686.93313066868;6688.83277918051;6640.269570347258;6643.734250629969;6646.634435106067;6654.600723956554;6657.533972621918;6122.816315510542;6124.387561243884;6153.876922461571;6173.166622505994;6184.412480814315;28386.148415547566;29207.707922920712;29304.413911721647;29514.852425737732;29905.551416728766;2100.4538819906934;2140.9785902140934;2142.8714277143267;2149.849510534263;2153.0956998060697;19173.616527669506;19502.31741889027;20434.69347959782;20477.897610512002;20502.692459613118;2428.295794219674;2429.8542087474807;2462.236706620953;2490.10356271742;2509.962350564743;130685.38629227362;135142;148063.25968370197;156118;156315.21842390829;14589.957405963174;14729.244042769025;14809.037412568148;14827.332770025374;14846.978708130446;23004.079836806675;23209.187678431175;23223.651645225335;23324.41688957756;23346.766532334797;9449.622015119456;9526.333156678966;9650;9654.71035868925;9670.75823104428;8824.823503529893;8852.557372131496;8861.778455538662;8928.955355223312;8946.910530894738;8967.50678712664;9045.231155351737;9173.81652366949;9302.395344302598;9401.200897923616;2572.5369433501774;2717.442924200534;2733.945321093567;2746.1761471558452;2841.7158284171833;947.2706016367496;959.7312752429331;973.2675190643819;981.1807141037314;986.4919566423298;19714.605707885763;19880.70178947317;20027.899860500896;20285.797142028685;20353.389398318075;991.7272749993665;1062.4050531702271;1064.2390690656218;1065.5897479470304;1074.8226542620537;10469.9476502617;10768.300060496043;10946.507407166697;11108.16688748347;11117;448852;458674;458865;463625;463643;116598.41700791611;119457.40271298587;120273;122301;123891.3805430967;1215.574548907893;1269.8349214602226;1275.9362031898554;1280.1614942212866;1286.8841804237663;21029.684554731386;21039.05324260381;21272.4681882948;21446.571068578854;21659.675104873124;58275.417245827;59248.70375648267;59592.404075958686;60092.69953650204;61527.384726153956;75503;75618;76116;76261;76382;14375.137491750529;14646.121232726071;14690.485832995793;14744.852551474349;14751.114933104049;22571.5485690282;22746;23000.19499317516;23133.88433057824;23333.53332933298;18483.815161848208;18652.253909843996;18662.06689665484;18811.6237675243;18850.905745471184;14847.480338188112;15235.162066086472;15380.769288460458;15399.461018864286;16361;18987.33544325942;19354.225830967167;19797;19867;20036.699449507978;14151.787723184367;14607.488737894122;14616.561503154922;14623.488177913721;14639;18348.17433215535;18611.90694046521;18691.924764953088;18762;18774.906125469286 11:44:57 INFO - PID 3699 | |3;mootools.html;1293.7283170534188;1361.6051345109918;1370.560911043404;1371.3897315694517;1372.3549931532182;14885.106893586379;15017.6996460071;15526.844731552681;15556.222188890553;15556.611084722888;15625.609359766011;15894;15900.204989750524;15907.44323948662;15909.2840822163;2570.126157106588;2618.908338208163;2620.6593142891493;2622.1478019643596;2624.042224588031;2754.8760305786286;2864.3268831824503;2934.691857354979;2936.207224049516;2938.3535622163195;2798.258461507702;2810.1990932584217;2841.9715802841915;2842.07632519431;2844.772418206549;2792.539231026878;2805.5370863807448;2812.282867868701;2817.7745780337523;2818.689944106147;2699.041840146759;2707.593860920858;2716.225875625457;2717.9592306115424;2719.7688196503323;14518.274086295643;14616.780748288784;14630.853691463055;14649.70700585993;15326.463573774865;7892.684292628288;8168.183181681844;8181.71364002263;8194.385421093442;8197.71308004217;5348.652337598043;5521.972390138064;5619.831405057814;5629.71851407432;5641.379448260688;1835.2567210279858;1879.962400751991;1895.848332133426;1936.3029309448586;1946.309060283593;26659.46681066387;26898.46203075947;26932.32669183247;26961.595576066375;26987.730122698915;6414.711337989802;6710.563813352116;6725.293844146392;6745.156855393076;6909.412699920563;17352.39266625662;17360.826391736173;17361.218745156497;17367.218475168647;17381.56546086358;7986.600669966595;7987.321077708344;8001.439899207;8003.119656837744;8009;6322.810315690536;7268.612672967248;7398.482106252618;7434.293742094493;7449.739259125905;4812.807487700523;5062.4684408137355;5083.74581270935;5199.1161502544655;5222.268882356473;1517.6130086827789;1521.4066514059525;1532.5862017255279;1533.7162624914426;1537.2313843078462;841.2975165736556;851.2423942691009;859.5745106172485;869.7434256894231;876.6537217798933;7613.200613935568;7797.142314345418;8703.564821758886;8916;8985.011648718635;943.127606963556;977.7653363192925;984.1880448629909;988.0613417253638;995.7211980645286;4690.484046754854;4843.612510999112;5012.649114562018;5049.318342023853;5158.406783219978;21072;21915.12339506402;21921.342359728915;21924.123035078417;21935;20870;21434;21456.785432145793;21745.238916638158;21762.782372176393;11045.558177672803;11196.720081998015;11354.545818167346;11408.087353011739;11433.656990290305;7925.682972681028;8046.597670116588;8048.718294859886;8157.143499932422;8207.220314070393;17906.641867162845;18433.539161520534;18452.26190952347;18804.24783008719;18818.717719234497;18238.817611823713;18316.175772090555;18362.632747344713;18550.628987419906;18803.623927521647;12049.397530123633;12253.816192757295;12344.567940122053;12371.13402061883;12377;12559.183653062522;12596.811047834106;13061;13608.251546165056;13890.374933128236;10970.122390208924;11006.229563930769;11036.392998385167;11268.549258029587;11386;10739.46302684847;10769.407682577534;10788.568457261621;10962.945185274342;11007.669769906915;9288.396254243453;9683.564239609093;9983.700488985342;10046.949765251127;10052.748681283027;7011.123609548807;7045.506814522934;7046.189688185924;7117.252688467636;7133.5719856808755;7392.519486233377;7409.259074092548;7437.516561423707;7471.589062601609;7497.737579184703 11:44:57 INFO - PID 3699 | |4;prototype.html;499.80757408397767;499.9000199960009;527.6622961304768;528.2419727690761;528.5428104689444;6346.14327065846;6376.776812811552;6404.046765736449;6443.516736244783;6456.354364563547;5982.581219314649;6207.193064901557;6298.874022519547;6311.715972781224;6327.5570710050315;5088.974555127219;5102.285680004802;5110.616703747215;5115.693058416488;5144.919566890939;4989.850304490871;4994.550490455858;5045.596352291826;5052.191649336107;5117.5650069744115;5021.899562008758;5033.144365457881;5060.671056381342;5082.433371147566;5128.230765385203;4940.5059494050665;4983.6013118950395;5008.398992120969;5011.173156429185;5029.798808047673;4521.615662668677;4541.296099104644;4550.749708766017;4551.112533056055;4552.294394368878;6191.164192833955;6241.251049874044;6250.281217659982;6253.249090254736;6315.147455093549;5789.507891829199;5810.54736315921;5873.207117039187;5887.381824908409;5892.705364731746;6729.250394897313;6733.293004234534;6754.493412994045;6755.324467553254;6782;4966.5778408835295;4970.975145124287;4973.62697797663;4976.034466385793;4977.228529577885;7034.613096279753;7101.218865924744;7132.5007249492555;7136.644037632885;7136.9302902158315;5066.240063990431;5072.134244152042;5094.923576146361;5111.386633603917;5160.354955630546;5158.535731784157;5163.328767260232;5166.29512260953;5180.818671346485;5181.974090129525;5783.94216057834;5824.388439213906;5838.211841401441;5861.120831875252;5862.70686465675;6628.734850605922;6732.528722989442;6735.29279425663;6819.693113809792;6823.215330236987;1666.6500034992546;1672.991635041817;1706.7269236922034;1758.5779412940801;1772.865366165655;1203.9525612716993;1243.9067069969608;1248.2572869142846;1254.874512548756;1267.258653687603;5900.350961394243;5908.291005079418;5973.432523910222;5992;6043.244594425697;6749.865002700017;6831.829204269834;6838.384545390939;6914.79255622322;6954.852449345903;5888.735006924699;5981.132735753292;6000.5799594028;6008.399160083956;6016.69916504173;3626.621883684236;3643.121933672422;3667.954632929602;3674.4855720199193;3697.926041479155;6586.934130658728;6646.208592391207;6705.161854768154;6715.429188518933;6745;7045.542039767399;7110.964445177741;7143.964280178566;7154.141503019671;7189.89215161773;6592.67036648156;6677.799666010027;6703.899441508382;6708.463322934153;6717.832054198684;6933.965330173519;6967.477439192182;6996.6006798639455;7000.7549735759;7001.859962800817;6912.135983002125;6912.308769123048;6944.756933507303;6966.7213311469;6988.475864310095;6708.362705542965;6722.050605324596;6723.865522689616;6729.764458243938;6749.898751518829;5056.544910958031;5057.216131499624;5060.696358218519;5063.316452279042;5085.745712714275;6936.618485983319;6956.617386043614;6978.267281935324;7000.47496437759;7009.474289428415;6723;6744.494162937897;6744.662766861539;6792.150981127359;6800.387965083166;6664.666766661744;6692.799216023527;6748.831279218059;6763.526553141233;6770;6370.458511026614;6380.489560835029;6520.641869437149;6580.8354791130605;6633.867322653424;6748.2576916538155;6750.29121942189;6752.155980502437;6756.831079223059;6762.526623136333;6473.449756770728;6490.7992021476175;6523.510736694672;6526.836829079311;6547.723193977129 11:44:57 INFO - PID 3699 | |5;yui.html;901.5582364641326;912.6714382822183;920.7145784806711;926.4811705444947;939.6100618243429;19347.322843700473;19888.105035273413;19897.502562435948;19899.701504477445;20968.475788105305;21810.127594896185;22571.54856902861;22589.322320330375;22752.431189220235;22754.31737047887;994.796066806303;1009.9343542669703;1010.434156872154;1010.9292349535535;1016.6390931219422;973.4159504297401;1008.5511947183506;1009.848522721594;1010.7271036820055;1011.1506334678868;1069.5561342043043;1069.9090577300938;1070.8661417322835;1071.587438836046;1071.9624813131543;1066.9039786419182;1069.4759567811793;1069.58820853971;1070.1599244593035;1074.1997212077013;782.4327362662082;782.5422128055095;782.9451938364317;783.2637320918376;783.4594130050247;20100.29648962294;20105.79894201054;20438.386848394573;20453.48866278355;20490.897545512176;21078.26226082095;21545.1381944722;21733.565328693494;22035.889820550794;22046.448838778997;17614.11929403525;17628.735568966476;17701.557461063447;18022.90988545049;18220.63558728818;3790.9810450947843;3832.9233415331814;3847.365184744514;3848.8845334639727;3859.4982652255308;133021.33489332587;133950.3302483491;136742;136861;138011;16585.917070414813;16684.916575417286;17075.658486830194;17121.91439042797;17156.828431715774;30925.072247832602;30981.69018309833;31041.689583104333;31474.37051258962;31533.52699709506;7745.8450830983065;8214.178582141738;8840.734777956672;9249.722508324761;9346.485943273185;4445.644348452116;4477.70894891831;4490.775461226991;4493.168763778712;4502.099580083931;5245.685258884403;5335.226392173113;5339.5728341732565;5351.839444816662;5390.407055223922;1037.2790910317408;1050.1231471721046;1060.6181774561153;1072.651388298806;1073.897979691928;605.7365046204915;611.5596770325359;615.602936106209;617.7745123030152;636.1793286660156;8920.553972301359;9390.750111140522;9503.904960950302;9655.613775449045;10149.695509134737;640.814163892466;651.4625434016955;662.6918482905376;663.6217356107069;664.727461740684;4475.686701930898;4477.3731677565165;4488.865334039984;4502.617277531381;4581.2440947243995;110424;112068;115416;116988;117344;95615.52192238995;96027.51986240024;96518.51740741251;97464.51267743616;99639;83959.58020209982;86782.56608716917;87162.56418717866;87921.56039219763;89134;12002.63992080239;12872.806907896576;12918.031147664151;13046.152000119962;13171.143875648053;36205.27589448249;39964.60035399609;40430.595694042684;40833.387499186945;41903.16193676049;50591.49408505868;51096.48903511066;51710.22434663558;51883.74058129685;53121.734391327795;17898.5525361867;17919.820801792335;18073.548161296072;18442.35451759182;18583.721244181077;19968.600627987067;20108.195672172948;20203.292884748964;20342.59314813725;20422.387328380173;18129;18446.81553184451;18495.53761155928;18593.814061859208;18615.255389784255;17473.47579572615;17561.209745561224;17569.736453952944;17593.384231551834;17654.823451765318;16645.667086658443;16744.581385465463;16808.327666893187;16817.579560511083;16841.915790420968;12212.32832194238;12321.199122057038;12510.18683785551;12586.18189817659;12588.118831681695;13460.125091868997;13673.179609223478;13727.750415425571;13778.724425511635;13804.102733322703 11:44:57 INFO - PID 3699 | __end_tp_report 11:44:57 INFO - PID 3699 | __start_cc_report 11:44:57 INFO - PID 3699 | _x_x_mozilla_cycle_collect,385 11:44:57 INFO - PID 3699 | __end_cc_report 11:44:57 INFO - PID 3699 | __startTimestamp1504637097908__endTimestamp 11:44:57 INFO - PID 3699 | 11:44:57 INFO - PID 3699 | ------- Summary: start ------- 11:44:57 INFO - PID 3699 | Number of tests: 6 11:44:57 INFO - PID 3699 | 11:44:57 INFO - PID 3699 | [#0] dojo.html Cycles:165 Average:6388.42 Median:6637.90 stddev:757.05 (11.4%) stddev-sans-first:720.06 11:44:57 INFO - PID 3699 | Values: 3318.9 3363.6 3372.4 3380.8 3398.4 6347.0 6459.8 6462.0 6467.1 6569.3 6494.1 6558.1 6581.2 6583.4 6593.2 5907.4 5928.8 5979.6 6010.3 6076.6 5883.2 5893.4 5955.6 5967.7 5969.2 5867.2 5957.6 5959.7 5982.2 5985.9 5898.6 5919.3 5965.2 5976.0 5995.1 5742.9 5863.9 5897.9 5938.3 5945.9 6363.9 6393.0 6415.2 6415.6 6451.3 6968.7 6996.1 7029.6 7035.9 7039.5 6965.8 6982.5 6987.1 7046.8 7054.3 6326.9 6399.6 6469.2 6499.7 6589.8 7175.0 7238.4 7254.0 7255.1 7276.7 6859.2 6879.6 6929.2 6934.6 6953.8 6947.3 6958.7 7037.9 7064.6 7092.3 6701.1 6762.7 6810.9 6910.9 7008.6 6926.3 6935.9 6939.4 6945.3 6950.9 5444.4 5463.6 5506.3 5516.1 5533.6 4746.4 4773.2 4808.5 4825.1 4831.6 5918.8 6024.8 6039.0 6102.9 6116.1 6898.7 6899.8 6900.3 6909.8 6910.2 5866.6 5968.6 5981.9 5982.1 5999.3 5912.9 5918.3 5918.6 5932.2 5932.8 6637.9 6654.8 6668.6 6676.2 6782.1 6978.2 6980.9 7040.3 7046.2 7061.8 6565.7 6645.6 6646.4 6647.1 6681.6 6849.8 6892.7 6945.3 6979.8 7003.6 6806.9 6902.0 6951.9 6985.9 7026.5 6814.4 6833.5 6849.2 6850.7 6859.2 6745.0 6774.3 6777.7 6790.6 6792.2 6636.1 6642.2 6643.1 6652.2 6732.6 6642.9 6682.6 6732.6 6830.6 6846.0 6528.6 6529.6 6553.1 6556.2 6558.5 11:44:57 INFO - PID 3699 | 11:44:57 INFO - PID 3699 | [#1] ext.html Cycles:180 Average:37806.66 Median:19576.06 stddev:52013.22 (265.7%) stddev-sans-first:52085.00 11:44:57 INFO - PID 3699 | Values: 826.1 868.8 893.2 896.4 1160.2 30736.0 31269.1 41073.4 42228.8 42416.0 42476.4 42915.1 42944.6 43081.8 43145.4 7667.4 7689.0 7801.4 7803.8 7829.3 7682.5 7753.5 7777.0 7780.0 7788.6 7717.2 7776.2 7780.3 7787.0 7789.7 7734.1 7748.8 7764.6 7776.4 7785.9 7679.7 7715.7 7716.7 7737.2 7764.3 36571.3 36588.3 36900.4 36952.3 36968.0 3544.7 3545.1 3555.5 3571.3 3574.4 30960.8 31085.0 31189.4 31287.5 31319.8 4993.4 5176.2 5202.1 5210.6 5229.1 179381.0 180572.0 181844.0 181940.0 182104.0 47058.0 47100.5 47177.8 47183.3 47374.8 49403.5 49448.0 49448.3 49463.5 49627.8 14309.0 16007.0 16229.7 16240.2 16254.8 12001.5 13085.3 13307.2 13316.4 13355.6 13352.0 13363.3 13416.7 13430.3 13512.7 5524.8 5576.0 5648.1 5682.4 5717.5 1005.9 1023.5 1026.4 1036.4 1042.1 22275.5 22366.8 22391.0 22439.2 22635.2 1115.5 1126.6 1135.1 1135.3 1135.8 11836.2 11884.7 11899.8 11943.2 11964.9 217312.0 218101.0 218414.0 219560.0 220028.0 52208.0 52558.2 55885.2 59151.0 59478.4 1479.3 1503.9 1518.1 1529.3 1531.0 27527.4 27554.3 27603.9 27682.6 27691.7 50459.0 50844.5 50950.5 51293.7 51425.5 199308.0 200865.0 201049.0 201138.0 201983.0 35596.6 35922.3 36028.0 37927.8 37973.4 44919.1 45362.5 45592.5 45641.5 45745.9 34122.8 34451.0 34551.3 34819.0 35274.5 33815.5 33828.0 33893.3 34212.3 34631.8 37538.8 37917.1 38050.8 38070.8 38081.4 32903.5 33290.0 33383.8 33422.0 33946.5 23759.6 24066.0 24099.2 24646.9 25001.5 11:44:57 INFO - PID 3699 | 11:44:57 INFO - PID 3699 | [#2] jquery.html Cycles:180 Average:34555.65 Median:23274.03 stddev:78298.50 (336.4%) stddev-sans-first:78476.56 11:44:57 INFO - PID 3699 | Values: 563.0 568.4 571.8 571.9 583.4 21895.1 22611.0 22619.7 22643.9 22713.2 23856.4 25895.7 25919.5 26022.3 26098.6 6656.1 6797.5 6799.4 6801.4 6802.4 6401.7 6699.6 6702.9 6703.8 6735.9 6655.9 6662.0 6667.7 6686.9 6688.8 6640.3 6643.7 6646.6 6654.6 6657.5 6122.8 6124.4 6153.9 6173.2 6184.4 28386.1 29207.7 29304.4 29514.9 29905.6 2100.5 2141.0 2142.9 2149.8 2153.1 19173.6 19502.3 20434.7 20477.9 20502.7 2428.3 2429.9 2462.2 2490.1 2510.0 130685.4 135142.0 148063.3 156118.0 156315.2 14590.0 14729.2 14809.0 14827.3 14847.0 23004.1 23209.2 23223.7 23324.4 23346.8 9449.6 9526.3 9650.0 9654.7 9670.8 8824.8 8852.6 8861.8 8929.0 8946.9 8967.5 9045.2 9173.8 9302.4 9401.2 2572.5 2717.4 2733.9 2746.2 2841.7 947.3 959.7 973.3 981.2 986.5 19714.6 19880.7 20027.9 20285.8 20353.4 991.7 1062.4 1064.2 1065.6 1074.8 10469.9 10768.3 10946.5 11108.2 11117.0 448852.0 458674.0 458865.0 463625.0 463643.0 116598.4 119457.4 120273.0 122301.0 123891.4 1215.6 1269.8 1275.9 1280.2 1286.9 21029.7 21039.1 21272.5 21446.6 21659.7 58275.4 59248.7 59592.4 60092.7 61527.4 75503.0 75618.0 76116.0 76261.0 76382.0 14375.1 14646.1 14690.5 14744.9 14751.1 22571.5 22746.0 23000.2 23133.9 23333.5 18483.8 18652.3 18662.1 18811.6 18850.9 14847.5 15235.2 15380.8 15399.5 16361.0 18987.3 19354.2 19797.0 19867.0 20036.7 14151.8 14607.5 14616.6 14623.5 14639.0 18348.2 18611.9 18691.9 18762.0 18774.9 11:44:57 INFO - PID 3699 | 11:44:57 INFO - PID 3699 | [#3] mootools.html Cycles:180 Average:9329.06 Median:26974.66 stddev:6679.44 (24.8%) stddev-sans-first:6670.89 11:44:57 INFO - PID 3699 | Values: 1293.7 1361.6 1370.6 1371.4 1372.4 14885.1 15017.7 15526.8 15556.2 15556.6 15625.6 15894.0 15900.2 15907.4 15909.3 2570.1 2618.9 2620.7 2622.1 2624.0 2754.9 2864.3 2934.7 2936.2 2938.4 2798.3 2810.2 2842.0 2842.1 2844.8 2792.5 2805.5 2812.3 2817.8 2818.7 2699.0 2707.6 2716.2 2718.0 2719.8 14518.3 14616.8 14630.9 14649.7 15326.5 7892.7 8168.2 8181.7 8194.4 8197.7 5348.7 5522.0 5619.8 5629.7 5641.4 1835.3 1880.0 1895.8 1936.3 1946.3 26659.5 26898.5 26932.3 26961.6 26987.7 6414.7 6710.6 6725.3 6745.2 6909.4 17352.4 17360.8 17361.2 17367.2 17381.6 7986.6 7987.3 8001.4 8003.1 8009.0 6322.8 7268.6 7398.5 7434.3 7449.7 4812.8 5062.5 5083.7 5199.1 5222.3 1517.6 1521.4 1532.6 1533.7 1537.2 841.3 851.2 859.6 869.7 876.7 7613.2 7797.1 8703.6 8916.0 8985.0 943.1 977.8 984.2 988.1 995.7 4690.5 4843.6 5012.6 5049.3 5158.4 21072.0 21915.1 21921.3 21924.1 21935.0 20870.0 21434.0 21456.8 21745.2 21762.8 11045.6 11196.7 11354.5 11408.1 11433.7 7925.7 8046.6 8048.7 8157.1 8207.2 17906.6 18433.5 18452.3 18804.2 18818.7 18238.8 18316.2 18362.6 18550.6 18803.6 12049.4 12253.8 12344.6 12371.1 12377.0 12559.2 12596.8 13061.0 13608.3 13890.4 10970.1 11006.2 11036.4 11268.5 11386.0 10739.5 10769.4 10788.6 10962.9 11007.7 9288.4 9683.6 9983.7 10046.9 10052.7 7011.1 7045.5 7046.2 7117.3 7133.6 7392.5 7409.3 7437.5 7471.6 7497.7 11:44:57 INFO - PID 3699 | 11:44:57 INFO - PID 3699 | [#4] prototype.html Cycles:180 Average:5654.05 Median:6276.06 stddev:1607.91 (25.6%) stddev-sans-first:1565.19 11:44:57 INFO - PID 3699 | Values: 499.8 499.9 527.7 528.2 528.5 6346.1 6376.8 6404.0 6443.5 6456.4 5982.6 6207.2 6298.9 6311.7 6327.6 5089.0 5102.3 5110.6 5115.7 5144.9 4989.9 4994.6 5045.6 5052.2 5117.6 5021.9 5033.1 5060.7 5082.4 5128.2 4940.5 4983.6 5008.4 5011.2 5029.8 4521.6 4541.3 4550.7 4551.1 4552.3 6191.2 6241.3 6250.3 6253.2 6315.1 5789.5 5810.5 5873.2 5887.4 5892.7 6729.3 6733.3 6754.5 6755.3 6782.0 4966.6 4971.0 4973.6 4976.0 4977.2 7034.6 7101.2 7132.5 7136.6 7136.9 5066.2 5072.1 5094.9 5111.4 5160.4 5158.5 5163.3 5166.3 5180.8 5182.0 5783.9 5824.4 5838.2 5861.1 5862.7 6628.7 6732.5 6735.3 6819.7 6823.2 1666.7 1673.0 1706.7 1758.6 1772.9 1204.0 1243.9 1248.3 1254.9 1267.3 5900.4 5908.3 5973.4 5992.0 6043.2 6749.9 6831.8 6838.4 6914.8 6954.9 5888.7 5981.1 6000.6 6008.4 6016.7 3626.6 3643.1 3668.0 3674.5 3697.9 6586.9 6646.2 6705.2 6715.4 6745.0 7045.5 7111.0 7144.0 7154.1 7189.9 6592.7 6677.8 6703.9 6708.5 6717.8 6934.0 6967.5 6996.6 7000.8 7001.9 6912.1 6912.3 6944.8 6966.7 6988.5 6708.4 6722.1 6723.9 6729.8 6749.9 5056.5 5057.2 5060.7 5063.3 5085.7 6936.6 6956.6 6978.3 7000.5 7009.5 6723.0 6744.5 6744.7 6792.2 6800.4 6664.7 6692.8 6748.8 6763.5 6770.0 6370.5 6380.5 6520.6 6580.8 6633.9 6748.3 6750.3 6752.2 6756.8 6762.5 6473.4 6490.8 6523.5 6526.8 6547.7 11:44:57 INFO - PID 3699 | 11:44:57 INFO - PID 3699 | [#5] yui.html Cycles:180 Average:23635.73 Median:20445.94 stddev:32783.14 (160.3%) stddev-sans-first:32830.66 11:44:57 INFO - PID 3699 | Values: 901.6 912.7 920.7 926.5 939.6 19347.3 19888.1 19897.5 19899.7 20968.5 21810.1 22571.5 22589.3 22752.4 22754.3 994.8 1009.9 1010.4 1010.9 1016.6 973.4 1008.6 1009.8 1010.7 1011.2 1069.6 1069.9 1070.9 1071.6 1072.0 1066.9 1069.5 1069.6 1070.2 1074.2 782.4 782.5 782.9 783.3 783.5 20100.3 20105.8 20438.4 20453.5 20490.9 21078.3 21545.1 21733.6 22035.9 22046.4 17614.1 17628.7 17701.6 18022.9 18220.6 3791.0 3832.9 3847.4 3848.9 3859.5 133021.3 133950.3 136742.0 136861.0 138011.0 16585.9 16684.9 17075.7 17121.9 17156.8 30925.1 30981.7 31041.7 31474.4 31533.5 7745.8 8214.2 8840.7 9249.7 9346.5 4445.6 4477.7 4490.8 4493.2 4502.1 5245.7 5335.2 5339.6 5351.8 5390.4 1037.3 1050.1 1060.6 1072.7 1073.9 605.7 611.6 615.6 617.8 636.2 8920.6 9390.8 9503.9 9655.6 10149.7 640.8 651.5 662.7 663.6 664.7 4475.7 4477.4 4488.9 4502.6 4581.2 110424.0 112068.0 115416.0 116988.0 117344.0 95615.5 96027.5 96518.5 97464.5 99639.0 83959.6 86782.6 87162.6 87921.6 89134.0 12002.6 12872.8 12918.0 13046.2 13171.1 36205.3 39964.6 40430.6 40833.4 41903.2 50591.5 51096.5 51710.2 51883.7 53121.7 17898.6 17919.8 18073.5 18442.4 18583.7 19968.6 20108.2 20203.3 20342.6 20422.4 18129.0 18446.8 18495.5 18593.8 18615.3 17473.5 17561.2 17569.7 17593.4 17654.8 16645.7 16744.6 16808.3 16817.6 16841.9 12212.3 12321.2 12510.2 12586.2 12588.1 13460.1 13673.2 13727.8 13778.7 13804.1 11:44:57 INFO - PID 3699 | -------- Summary: end -------- 11:44:57 INFO - PID 3699 | 11:44:58 INFO - TEST-INFO | 3699: exit 0 11:44:58 INFO - TEST-OK | dromaeo_css | took 1112707ms 11:44:58 INFO - TEST-START | kraken 11:44:58 INFO - Initialising browser for kraken test... 11:44:58 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46051/getInfo.html -profile /tmp/tmp4V0eg3/profile 11:44:58 INFO - TEST-INFO | started process 4075 (/builds/slave/test/build/application/firefox/firefox http://localhost:46051/getInfo.html) 11:45:03 INFO - TEST-INFO | 4075: exit 0 11:45:03 INFO - Browser initialized. 11:45:03 INFO - Running cycle 1/1 for kraken test... 11:45:03 INFO - Using env: {'DISPLAY': ':0', 11:45:03 INFO - 'HOME': '/home/cltbld', 11:45:03 INFO - 'JSGC_DISABLE_POISONING': '1', 11:45:03 INFO - 'LANG': 'en_US.UTF-8', 11:45:03 INFO - 'LANGUAGE': 'en_US:en', 11:45:03 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:45:03 INFO - 'LOGNAME': 'cltbld', 11:45:03 INFO - 'MAIL': '/var/mail/cltbld', 11:45:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:45:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:45:03 INFO - 'MOZ_CRASHREPORTER': '1', 11:45:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:03 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:45:03 INFO - 'MOZ_NO_REMOTE': '1', 11:45:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:45:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:45:03 INFO - 'NO_EM_RESTART': '1', 11:45:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:45:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:03 INFO - 'PWD': '/builds/slave/test', 11:45:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:45:03 INFO - 'RUST_BACKTRACE': 'full', 11:45:03 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:45:03 INFO - 'SHELL': '/bin/bash', 11:45:03 INFO - 'SHLVL': '1', 11:45:03 INFO - 'STYLO_FORCE_ENABLED': '1', 11:45:03 INFO - 'STYLO_THREADS': '1', 11:45:03 INFO - 'TERM': 'linux', 11:45:03 INFO - 'TMOUT': '86400', 11:45:03 INFO - 'USER': 'cltbld', 11:45:03 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398', 11:45:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:03 INFO - '_': '/tools/buildbot/bin/python'} 11:45:03 INFO - TEST-INFO | started process 4257 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4V0eg3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:45:04 INFO - PID 4257 | 11:45:04 INFO - PID 4257 | (/builds/slave/test/build/application/firefox/firefox:4310): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:45:04 INFO - PID 4257 | 11:45:15 INFO - PID 4257 | RSS: Main: 172273664 11:45:15 INFO - PID 4257 | 11:45:17 INFO - PID 4257 | Cycle 1(1): loaded http://localhost:46051/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46051/tests/kraken/driver.html?testName=audio-beat-detection) 11:45:18 INFO - PID 4257 | RSS: Main: 177954816 11:45:18 INFO - PID 4257 | 11:45:21 INFO - PID 4257 | Cycle 1(1): loaded http://localhost:46051/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46051/tests/kraken/driver.html?testName=audio-dft) 11:45:22 INFO - PID 4257 | RSS: Main: 178966528 11:45:22 INFO - PID 4257 | 11:45:25 INFO - PID 4257 | Cycle 1(1): loaded http://localhost:46051/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46051/tests/kraken/driver.html?testName=audio-fft) 11:45:25 INFO - PID 4257 | RSS: Main: 173408256 11:45:25 INFO - PID 4257 | 11:45:27 INFO - PID 4257 | Cycle 1(1): loaded http://localhost:46051/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46051/tests/kraken/driver.html?testName=audio-oscillator) 11:45:28 INFO - PID 4257 | RSS: Main: 173813760 11:45:28 INFO - PID 4257 | 11:45:30 INFO - PID 4257 | Cycle 1(1): loaded http://localhost:46051/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46051/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:45:31 INFO - PID 4257 | RSS: Main: 174481408 11:45:31 INFO - PID 4257 | 11:45:34 INFO - PID 4257 | Cycle 1(1): loaded http://localhost:46051/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46051/tests/kraken/driver.html?testName=imaging-darkroom) 11:45:35 INFO - PID 4257 | RSS: Main: 176603136 11:45:35 INFO - PID 4257 | 11:45:38 INFO - PID 4257 | Cycle 1(1): loaded http://localhost:46051/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46051/tests/kraken/driver.html?testName=imaging-desaturate) 11:45:39 INFO - PID 4257 | RSS: Main: 176803840 11:45:39 INFO - PID 4257 | 11:45:42 INFO - PID 4257 | Cycle 1(1): loaded http://localhost:46051/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46051/tests/kraken/driver.html?testName=json-parse-financial) 11:45:42 INFO - PID 4257 | RSS: Main: 176771072 11:45:42 INFO - PID 4257 | 11:45:44 INFO - PID 4257 | Cycle 1(1): loaded http://localhost:46051/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46051/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:45:44 INFO - PID 4257 | RSS: Main: 176340992 11:45:44 INFO - PID 4257 | 11:45:46 INFO - PID 4257 | Cycle 1(1): loaded http://localhost:46051/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46051/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:45:47 INFO - PID 4257 | RSS: Main: 177086464 11:45:47 INFO - PID 4257 | 11:45:49 INFO - PID 4257 | Cycle 1(1): loaded http://localhost:46051/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46051/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:45:49 INFO - PID 4257 | RSS: Main: 173613056 11:45:49 INFO - PID 4257 | 11:45:52 INFO - PID 4257 | Cycle 1(1): loaded http://localhost:46051/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46051/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:45:53 INFO - PID 4257 | RSS: Main: 174194688 11:45:53 INFO - PID 4257 | 11:45:56 INFO - PID 4257 | Cycle 1(1): loaded http://localhost:46051/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46051/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:45:56 INFO - PID 4257 | RSS: Main: 175022080 11:45:56 INFO - PID 4257 | 11:45:58 INFO - PID 4257 | Cycle 1(1): loaded http://localhost:46051/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46051/tests/kraken/driver.html?testName=ai-astar) 11:45:58 INFO - PID 4257 | RSS: Main: 175771648 11:45:58 INFO - PID 4257 | 11:45:58 INFO - PID 4257 | __start_tp_report 11:45:58 INFO - PID 4257 | _x_x_mozilla_page_load 11:45:58 INFO - PID 4257 | _x_x_mozilla_page_load_details 11:45:58 INFO - PID 4257 | |i|pagename|runs| 11:45:58 INFO - PID 4257 | |0;ai-astar;99;106;99;107;106;107;110;98;108;106 11:45:58 INFO - PID 4257 | |1;audio-beat-detection;149;117;147;166;115;148;142;115;172;117 11:45:58 INFO - PID 4257 | |2;audio-dft;165;167;164;164;165;162;161;178;168;164 11:45:58 INFO - PID 4257 | |3;audio-fft;74;74;76;182;78;75;74;73;170;75 11:45:58 INFO - PID 4257 | |4;audio-oscillator;80;79;139;79;79;79;128;79;79;131 11:45:58 INFO - PID 4257 | |5;imaging-gaussian-blur;114;116;114;114;114;114;114;114;113;114 11:45:58 INFO - PID 4257 | |6;imaging-darkroom;151;151;150;148;148;150;149;148;148;149 11:45:58 INFO - PID 4257 | |7;imaging-desaturate;94;94;94;93;94;94;95;93;94;94 11:45:58 INFO - PID 4257 | |8;json-parse-financial;54;56;56;52;54;53;52;53;52;55 11:45:58 INFO - PID 4257 | |9;json-stringify-tinderbox;38;40;39;39;38;38;39;45;39;38 11:45:58 INFO - PID 4257 | |10;stanford-crypto-aes;73;74;74;76;74;76;75;73;74;73 11:45:58 INFO - PID 4257 | |11;stanford-crypto-ccm;120;121;120;122;122;119;116;119;116;162 11:45:58 INFO - PID 4257 | |12;stanford-crypto-pbkdf2;173;171;170;169;176;175;167;166;171;168 11:45:58 INFO - PID 4257 | |13;stanford-crypto-sha256-iterative;68;67;63;64;62;63;62;63;74;63 11:45:58 INFO - PID 4257 | __end_tp_report 11:45:58 INFO - PID 4257 | __start_cc_report 11:45:58 INFO - PID 4257 | _x_x_mozilla_cycle_collect,934 11:45:58 INFO - PID 4257 | __end_cc_report 11:45:58 INFO - PID 4257 | __startTimestamp1504637158905__endTimestamp 11:45:58 INFO - PID 4257 | 11:45:58 INFO - PID 4257 | ------- Summary: start ------- 11:45:58 INFO - PID 4257 | Number of tests: 14 11:45:58 INFO - PID 4257 | 11:45:58 INFO - PID 4257 | [#0] ai-astar Cycles:10 Average:104.60 Median:109.00 stddev:4.27 (3.9%) stddev-sans-first:4.02 11:45:58 INFO - PID 4257 | Values: 99.0 106.0 99.0 107.0 106.0 107.0 110.0 98.0 108.0 106.0 11:45:58 INFO - PID 4257 | 11:45:58 INFO - PID 4257 | [#1] audio-beat-detection Cycles:10 Average:138.80 Median:147.50 stddev:21.58 (14.6%) stddev-sans-first:22.57 11:45:58 INFO - PID 4257 | Values: 149.0 117.0 147.0 166.0 115.0 148.0 142.0 115.0 172.0 117.0 11:45:58 INFO - PID 4257 | 11:45:58 INFO - PID 4257 | [#2] audio-dft Cycles:10 Average:165.80 Median:165.00 stddev:4.76 (2.9%) stddev-sans-first:5.04 11:45:58 INFO - PID 4257 | Values: 165.0 167.0 164.0 164.0 165.0 162.0 161.0 178.0 168.0 164.0 11:45:58 INFO - PID 4257 | 11:45:58 INFO - PID 4257 | [#3] audio-fft Cycles:10 Average:95.10 Median:74.50 stddev:42.75 (57.4%) stddev-sans-first:44.66 11:45:58 INFO - PID 4257 | Values: 74.0 74.0 76.0 182.0 78.0 75.0 74.0 73.0 170.0 75.0 11:45:58 INFO - PID 4257 | 11:45:58 INFO - PID 4257 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:79.00 stddev:25.99 (32.9%) stddev-sans-first:26.98 11:45:58 INFO - PID 4257 | Values: 80.0 79.0 139.0 79.0 79.0 79.0 128.0 79.0 79.0 131.0 11:45:58 INFO - PID 4257 | 11:45:58 INFO - PID 4257 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 11:45:58 INFO - PID 4257 | Values: 114.0 116.0 114.0 114.0 114.0 114.0 114.0 114.0 113.0 114.0 11:45:58 INFO - PID 4257 | 11:45:58 INFO - PID 4257 | [#6] imaging-darkroom Cycles:10 Average:149.20 Median:149.50 stddev:1.23 (0.8%) stddev-sans-first:1.12 11:45:58 INFO - PID 4257 | Values: 151.0 151.0 150.0 148.0 148.0 150.0 149.0 148.0 148.0 149.0 11:45:58 INFO - PID 4257 | 11:45:58 INFO - PID 4257 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 11:45:58 INFO - PID 4257 | Values: 94.0 94.0 94.0 93.0 94.0 94.0 95.0 93.0 94.0 94.0 11:45:58 INFO - PID 4257 | 11:45:58 INFO - PID 4257 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:1.57 (2.9%) stddev-sans-first:1.66 11:45:58 INFO - PID 4257 | Values: 54.0 56.0 56.0 52.0 54.0 53.0 52.0 53.0 52.0 55.0 11:45:58 INFO - PID 4257 | 11:45:58 INFO - PID 4257 | [#9] json-stringify-tinderbox Cycles:10 Average:39.30 Median:39.00 stddev:2.11 (5.4%) stddev-sans-first:2.19 11:45:58 INFO - PID 4257 | Values: 38.0 40.0 39.0 39.0 38.0 38.0 39.0 45.0 39.0 38.0 11:45:58 INFO - PID 4257 | 11:45:58 INFO - PID 4257 | [#10] stanford-crypto-aes Cycles:10 Average:74.20 Median:74.00 stddev:1.14 (1.5%) stddev-sans-first:1.12 11:45:58 INFO - PID 4257 | Values: 73.0 74.0 74.0 76.0 74.0 76.0 75.0 73.0 74.0 73.0 11:45:58 INFO - PID 4257 | 11:45:58 INFO - PID 4257 | [#11] stanford-crypto-ccm Cycles:10 Average:123.70 Median:120.50 stddev:13.62 (11.3%) stddev-sans-first:14.38 11:45:58 INFO - PID 4257 | Values: 120.0 121.0 120.0 122.0 122.0 119.0 116.0 119.0 116.0 162.0 11:45:58 INFO - PID 4257 | 11:45:58 INFO - PID 4257 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.60 Median:171.00 stddev:3.31 (1.9%) stddev-sans-first:3.39 11:45:58 INFO - PID 4257 | Values: 173.0 171.0 170.0 169.0 176.0 175.0 167.0 166.0 171.0 168.0 11:45:58 INFO - PID 4257 | 11:45:58 INFO - PID 4257 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:63.50 stddev:3.78 (6.0%) stddev-sans-first:3.84 11:45:58 INFO - PID 4257 | Values: 68.0 67.0 63.0 64.0 62.0 63.0 62.0 63.0 74.0 63.0 11:45:58 INFO - PID 4257 | -------- Summary: end -------- 11:45:58 INFO - PID 4257 | 11:45:59 INFO - PID 4257 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:45:59 INFO - TEST-INFO | 4257: exit 0 11:45:59 INFO - TEST-OK | kraken | took 61031ms 11:45:59 INFO - SUITE-END | took 1173s 11:46:00 INFO - Completed test suite (00:19:34) 11:46:00 INFO - javascript benchmark 11:46:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8654.600651854265, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3318.8838390656333, 3363.5627368442097, 3372.376110419571, 3380.7633465657404, 3398.371301309258, 6347.047942808581, 6459.838504037401, 6461.967690161543, 6467.094606755047, 6569.277379488252, 6494.123293355396, 6558.114654521638, 6581.210254769434, 6583.440407565363, 6593.241777195624, 5907.379725128864, 5928.7628494860155, 5979.641221526712, 6010.338862725097, 6076.635401875879, 5883.17635531026, 5893.351731309553, 5955.553333499983, 5967.701614919259, 5969.194158788551, 5867.178594996704, 5957.553183511254, 5959.672218028007, 5982.192404025444, 5985.910211346833, 5898.616589921684, 5919.3192782829665, 5965.164876917235, 5975.9701201494145, 5995.130706047598, 5742.856428589277, 5863.882722345529, 5897.852553686149, 5938.317093534256, 5945.8810823783715, 6363.9045414318825, 6392.968035159794, 6415.1981002374705, 6415.582987105823, 6451.322611125811, 6968.651567421659, 6996.090508233948, 7029.648517574101, 7035.859282814366, 7039.472039597, 6965.756198533078, 6982.476314276399, 6987.056747339095, 7046.7533636323, 7054.329838665319, 6326.936730632727, 6399.584027038228, 6469.223693156851, 6499.707513161919, 6589.769358072444, 7175.0, 7238.384737297389, 7253.963730181315, 7255.09311336083, 7276.745313913988, 6859.2454829968665, 6879.58722476643, 6929.237783843773, 6934.618595977169, 6953.756618518429, 6947.270536593686, 6958.686859091353, 7037.944308353685, 7064.576125432491, 7092.25531319204, 6701.128853249046, 6762.661866906635, 6810.897836532456, 6910.930890691029, 7008.614526201108, 6926.341997510229, 6935.861282774316, 6939.444844412435, 6945.340192681687, 6950.860982780316, 5444.40111587725, 5463.644863083966, 5506.339239291311, 5516.117421212587, 5533.584981126432, 4746.38297021385, 4773.164696178155, 4808.495108013608, 4825.10735513931, 4831.589314908202, 5918.763249470059, 6024.819255422344, 6038.969805150946, 6102.877942441215, 6116.143739876421, 6898.655067246617, 6899.827504312432, 6900.275471075565, 6909.792706218822, 6910.170779506491, 5866.589338746332, 5968.582199246098, 5981.850453738692, 5982.0727787193055, 5999.310079340845, 5912.940870591239, 5918.289805223315, 5918.585699001029, 5932.199153114274, 5932.822015339547, 6637.900431493532, 6654.800355989328, 6668.599884006975, 6676.165479315086, 6782.118324617768, 6978.232394436506, 6980.930190698028, 7040.331168539083, 7046.189688185924, 7061.752838650623, 6565.671716414065, 6645.60126392418, 6646.4350530205475, 6647.135872336566, 6681.5656982296, 6849.794506164823, 6892.724291028303, 6945.305469453015, 6979.790606281819, 7003.6148011857795, 6806.863862722619, 6902.033715279966, 6951.930480695129, 6985.9301406987315, 7026.508144430045, 6814.386705196556, 6833.521653484408, 6849.212340580698, 6850.691718872563, 6859.176898772179, 6744.966275168593, 6774.288699686659, 6777.728890844311, 6790.558613690095, 6792.218894827158, 6636.070950067091, 6642.169728783902, 6643.136392269167, 6652.201735791736, 6732.629705366252, 6642.900356494753, 6682.565633233824, 6732.596044237362, 6830.59016459014, 6846.0, 6528.640924749185, 6529.608223506605, 6553.148090748172, 6556.213254409501, 6558.508111891531], "value": 6332.528537866201, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [826.0912995704725, 868.8436081505329, 893.2273583350404, 896.4038914122107, 1160.1588848085134, 30736.0, 31269.061928142164, 41073.3838992415, 42228.788856055755, 42416.0, 42476.36285455721, 42915.14169716604, 42944.57055429445, 43081.78459107708, 43145.35281970765, 7667.386609071261, 7689.0, 7801.414893882953, 7803.8049048773955, 7829.295363417291, 7682.500637458547, 7753.457257991915, 7776.961115194416, 7779.961100194491, 7788.6105694715325, 7717.18969508202, 7776.183500732426, 7780.338671212954, 7787.0, 7789.727359542417, 7734.1492435832015, 7748.767536973844, 7764.611769411564, 7776.416768742339, 7785.922140778604, 7679.654415551314, 7715.729949451742, 7716.6913323467, 7737.187595302468, 7764.301212890867, 36571.26857462862, 36588.26823463543, 36900.446493302625, 36952.26095478102, 36968.0, 3544.6632569906074, 3545.149164200599, 3555.484454754072, 3571.2857428514403, 3574.374484465234, 30960.845195773876, 31085.0, 31189.37621247585, 31287.530687039714, 31319.843400783077, 4993.375828021497, 5176.249443830623, 5202.08963431398, 5210.557102646279, 5229.08491014071, 179381.0, 180572.0, 181844.0, 181940.0, 182104.0, 47058.0, 47100.5289947103, 47177.76411117991, 47183.292250616265, 47374.763126184145, 49403.50596494061, 49448.0, 49448.25827612517, 49463.505364946606, 49627.75186124119, 14309.0, 16007.0, 16229.67540649204, 16240.18799060066, 16254.75617865733, 12001.459934302977, 13085.345732713517, 13307.201567905957, 13316.400761965735, 13355.599332020054, 13352.0, 13363.26502042397, 13416.664583385493, 13430.328483575782, 13512.662183445493, 5524.779008839601, 5576.0, 5648.124540696199, 5682.431756824367, 5717.456841600041, 1005.8591797148405, 1023.5240613114788, 1026.440589878518, 1036.4144258493927, 1042.0985847242082, 22275.48977013387, 22366.776332236794, 22391.0, 22439.21462748796, 22635.20776772805, 1115.4924509348227, 1126.594426006637, 1135.0522313868012, 1135.28477059452, 1135.8125909224884, 11836.171467997157, 11884.702882428008, 11899.762004759856, 11943.223690460092, 11964.940175299067, 217312.0, 218101.0, 218414.0, 219560.0, 220028.0, 52208.0, 52558.21162682563, 55885.161722574194, 59151.0, 59478.40521594815, 1479.2677624575904, 1503.8721708654668, 1518.096732444216, 1529.2812378182236, 1531.035447667962, 27527.449451011267, 27554.311142221606, 27603.86198068997, 27682.584761229, 27691.72308276972, 50459.0, 50844.49155508546, 50950.490495096055, 51293.7435312821, 51425.48574514357, 199308.0, 200865.0, 201049.0, 201138.0, 201983.0, 35596.64403356037, 35922.28155436929, 36028.0, 37927.81036094802, 37973.43039854349, 44919.1016179668, 45362.546374535836, 45592.54407455883, 45641.543584563726, 45745.941432636275, 34122.829385853904, 34451.0, 34551.30897382089, 34819.0, 35274.470882936264, 33815.492767609, 33828.0, 33893.3221335567, 34212.31575368528, 34631.82684086564, 37538.81230593829, 37917.05207369838, 38050.80974595109, 38070.809645951595, 38081.4287785689, 32903.506447402826, 33290.0, 33383.83308083444, 33422.0, 33946.49080263748, 23759.643605345587, 24066.0, 24099.156529521384, 24646.876765616056, 25001.499970000135], "value": 17508.595946610472, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.0, 568.3974986514295, 571.7827225654254, 571.9399463056379, 583.3845293215658, 21895.12419503218, 22611.0, 22619.660705089416, 22643.886780566074, 22713.205037823638, 23856.40358991026, 25895.74104258957, 25919.48161036778, 26022.349441263977, 26098.608520872156, 6656.068150458927, 6797.490188235877, 6799.422049125806, 6801.353871382235, 6802.4217941475035, 6401.679916004205, 6699.598024118544, 6702.932970670304, 6703.798886033427, 6735.932640673604, 6655.866882662343, 6662.034005069262, 6667.666616669171, 6686.93313066868, 6688.83277918051, 6640.269570347258, 6643.734250629969, 6646.634435106067, 6654.600723956554, 6657.533972621918, 6122.816315510542, 6124.387561243884, 6153.876922461571, 6173.166622505994, 6184.412480814315, 28386.148415547566, 29207.707922920712, 29304.413911721647, 29514.852425737732, 29905.551416728766, 2100.4538819906934, 2140.9785902140934, 2142.8714277143267, 2149.849510534263, 2153.0956998060697, 19173.616527669506, 19502.31741889027, 20434.69347959782, 20477.897610512002, 20502.692459613118, 2428.295794219674, 2429.8542087474807, 2462.236706620953, 2490.10356271742, 2509.962350564743, 130685.38629227362, 135142.0, 148063.25968370197, 156118.0, 156315.21842390829, 14589.957405963174, 14729.244042769025, 14809.037412568148, 14827.332770025374, 14846.978708130446, 23004.079836806675, 23209.187678431175, 23223.651645225335, 23324.41688957756, 23346.766532334797, 9449.622015119456, 9526.333156678966, 9650.0, 9654.71035868925, 9670.75823104428, 8824.823503529893, 8852.557372131496, 8861.778455538662, 8928.955355223312, 8946.910530894738, 8967.50678712664, 9045.231155351737, 9173.81652366949, 9302.395344302598, 9401.200897923616, 2572.5369433501774, 2717.442924200534, 2733.945321093567, 2746.1761471558452, 2841.7158284171833, 947.2706016367496, 959.7312752429331, 973.2675190643819, 981.1807141037314, 986.4919566423298, 19714.605707885763, 19880.70178947317, 20027.899860500896, 20285.797142028685, 20353.389398318075, 991.7272749993665, 1062.4050531702271, 1064.2390690656218, 1065.5897479470304, 1074.8226542620537, 10469.9476502617, 10768.300060496043, 10946.507407166697, 11108.16688748347, 11117.0, 448852.0, 458674.0, 458865.0, 463625.0, 463643.0, 116598.41700791611, 119457.40271298587, 120273.0, 122301.0, 123891.3805430967, 1215.574548907893, 1269.8349214602226, 1275.9362031898554, 1280.1614942212866, 1286.8841804237663, 21029.684554731386, 21039.05324260381, 21272.4681882948, 21446.571068578854, 21659.675104873124, 58275.417245827, 59248.70375648267, 59592.404075958686, 60092.69953650204, 61527.384726153956, 75503.0, 75618.0, 76116.0, 76261.0, 76382.0, 14375.137491750529, 14646.121232726071, 14690.485832995793, 14744.852551474349, 14751.114933104049, 22571.5485690282, 22746.0, 23000.19499317516, 23133.88433057824, 23333.53332933298, 18483.815161848208, 18652.253909843996, 18662.06689665484, 18811.6237675243, 18850.905745471184, 14847.480338188112, 15235.162066086472, 15380.769288460458, 15399.461018864286, 16361.0, 18987.33544325942, 19354.225830967167, 19797.0, 19867.0, 20036.699449507978, 14151.787723184367, 14607.488737894122, 14616.561503154922, 14623.488177913721, 14639.0, 18348.17433215535, 18611.90694046521, 18691.924764953088, 18762.0, 18774.906125469286], "value": 12124.787384854984, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1293.7283170534188, 1361.6051345109918, 1370.560911043404, 1371.3897315694517, 1372.3549931532182, 14885.106893586379, 15017.6996460071, 15526.844731552681, 15556.222188890553, 15556.611084722888, 15625.609359766011, 15894.0, 15900.204989750524, 15907.44323948662, 15909.2840822163, 2570.126157106588, 2618.908338208163, 2620.6593142891493, 2622.1478019643596, 2624.042224588031, 2754.8760305786286, 2864.3268831824503, 2934.691857354979, 2936.207224049516, 2938.3535622163195, 2798.258461507702, 2810.1990932584217, 2841.9715802841915, 2842.07632519431, 2844.772418206549, 2792.539231026878, 2805.5370863807448, 2812.282867868701, 2817.7745780337523, 2818.689944106147, 2699.041840146759, 2707.593860920858, 2716.225875625457, 2717.9592306115424, 2719.7688196503323, 14518.274086295643, 14616.780748288784, 14630.853691463055, 14649.70700585993, 15326.463573774865, 7892.684292628288, 8168.183181681844, 8181.71364002263, 8194.385421093442, 8197.71308004217, 5348.652337598043, 5521.972390138064, 5619.831405057814, 5629.71851407432, 5641.379448260688, 1835.2567210279858, 1879.962400751991, 1895.848332133426, 1936.3029309448586, 1946.309060283593, 26659.46681066387, 26898.46203075947, 26932.32669183247, 26961.595576066375, 26987.730122698915, 6414.711337989802, 6710.563813352116, 6725.293844146392, 6745.156855393076, 6909.412699920563, 17352.39266625662, 17360.826391736173, 17361.218745156497, 17367.218475168647, 17381.56546086358, 7986.600669966595, 7987.321077708344, 8001.439899207, 8003.119656837744, 8009.0, 6322.810315690536, 7268.612672967248, 7398.482106252618, 7434.293742094493, 7449.739259125905, 4812.807487700523, 5062.4684408137355, 5083.74581270935, 5199.1161502544655, 5222.268882356473, 1517.6130086827789, 1521.4066514059525, 1532.5862017255279, 1533.7162624914426, 1537.2313843078462, 841.2975165736556, 851.2423942691009, 859.5745106172485, 869.7434256894231, 876.6537217798933, 7613.200613935568, 7797.142314345418, 8703.564821758886, 8916.0, 8985.011648718635, 943.127606963556, 977.7653363192925, 984.1880448629909, 988.0613417253638, 995.7211980645286, 4690.484046754854, 4843.612510999112, 5012.649114562018, 5049.318342023853, 5158.406783219978, 21072.0, 21915.12339506402, 21921.342359728915, 21924.123035078417, 21935.0, 20870.0, 21434.0, 21456.785432145793, 21745.238916638158, 21762.782372176393, 11045.558177672803, 11196.720081998015, 11354.545818167346, 11408.087353011739, 11433.656990290305, 7925.682972681028, 8046.597670116588, 8048.718294859886, 8157.143499932422, 8207.220314070393, 17906.641867162845, 18433.539161520534, 18452.26190952347, 18804.24783008719, 18818.717719234497, 18238.817611823713, 18316.175772090555, 18362.632747344713, 18550.628987419906, 18803.623927521647, 12049.397530123633, 12253.816192757295, 12344.567940122053, 12371.13402061883, 12377.0, 12559.183653062522, 12596.811047834106, 13061.0, 13608.251546165056, 13890.374933128236, 10970.122390208924, 11006.229563930769, 11036.392998385167, 11268.549258029587, 11386.0, 10739.46302684847, 10769.407682577534, 10788.568457261621, 10962.945185274342, 11007.669769906915, 9288.396254243453, 9683.564239609093, 9983.700488985342, 10046.949765251127, 10052.748681283027, 7011.123609548807, 7045.506814522934, 7046.189688185924, 7117.252688467636, 7133.5719856808755, 7392.519486233377, 7409.259074092548, 7437.516561423707, 7471.589062601609, 7497.737579184703], "value": 6675.831300920328, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.80757408397767, 499.9000199960009, 527.6622961304768, 528.2419727690761, 528.5428104689444, 6346.14327065846, 6376.776812811552, 6404.046765736449, 6443.516736244783, 6456.354364563547, 5982.581219314649, 6207.193064901557, 6298.874022519547, 6311.715972781224, 6327.5570710050315, 5088.974555127219, 5102.285680004802, 5110.616703747215, 5115.693058416488, 5144.919566890939, 4989.850304490871, 4994.550490455858, 5045.596352291826, 5052.191649336107, 5117.5650069744115, 5021.899562008758, 5033.144365457881, 5060.671056381342, 5082.433371147566, 5128.230765385203, 4940.5059494050665, 4983.6013118950395, 5008.398992120969, 5011.173156429185, 5029.798808047673, 4521.615662668677, 4541.296099104644, 4550.749708766017, 4551.112533056055, 4552.294394368878, 6191.164192833955, 6241.251049874044, 6250.281217659982, 6253.249090254736, 6315.147455093549, 5789.507891829199, 5810.54736315921, 5873.207117039187, 5887.381824908409, 5892.705364731746, 6729.250394897313, 6733.293004234534, 6754.493412994045, 6755.324467553254, 6782.0, 4966.5778408835295, 4970.975145124287, 4973.62697797663, 4976.034466385793, 4977.228529577885, 7034.613096279753, 7101.218865924744, 7132.5007249492555, 7136.644037632885, 7136.9302902158315, 5066.240063990431, 5072.134244152042, 5094.923576146361, 5111.386633603917, 5160.354955630546, 5158.535731784157, 5163.328767260232, 5166.29512260953, 5180.818671346485, 5181.974090129525, 5783.94216057834, 5824.388439213906, 5838.211841401441, 5861.120831875252, 5862.70686465675, 6628.734850605922, 6732.528722989442, 6735.29279425663, 6819.693113809792, 6823.215330236987, 1666.6500034992546, 1672.991635041817, 1706.7269236922034, 1758.5779412940801, 1772.865366165655, 1203.9525612716993, 1243.9067069969608, 1248.2572869142846, 1254.874512548756, 1267.258653687603, 5900.350961394243, 5908.291005079418, 5973.432523910222, 5992.0, 6043.244594425697, 6749.865002700017, 6831.829204269834, 6838.384545390939, 6914.79255622322, 6954.852449345903, 5888.735006924699, 5981.132735753292, 6000.5799594028, 6008.399160083956, 6016.69916504173, 3626.621883684236, 3643.121933672422, 3667.954632929602, 3674.4855720199193, 3697.926041479155, 6586.934130658728, 6646.208592391207, 6705.161854768154, 6715.429188518933, 6745.0, 7045.542039767399, 7110.964445177741, 7143.964280178566, 7154.141503019671, 7189.89215161773, 6592.67036648156, 6677.799666010027, 6703.899441508382, 6708.463322934153, 6717.832054198684, 6933.965330173519, 6967.477439192182, 6996.6006798639455, 7000.7549735759, 7001.859962800817, 6912.135983002125, 6912.308769123048, 6944.756933507303, 6966.7213311469, 6988.475864310095, 6708.362705542965, 6722.050605324596, 6723.865522689616, 6729.764458243938, 6749.898751518829, 5056.544910958031, 5057.216131499624, 5060.696358218519, 5063.316452279042, 5085.745712714275, 6936.618485983319, 6956.617386043614, 6978.267281935324, 7000.47496437759, 7009.474289428415, 6723.0, 6744.494162937897, 6744.662766861539, 6792.150981127359, 6800.387965083166, 6664.666766661744, 6692.799216023527, 6748.831279218059, 6763.526553141233, 6770.0, 6370.458511026614, 6380.489560835029, 6520.641869437149, 6580.8354791130605, 6633.867322653424, 6748.2576916538155, 6750.29121942189, 6752.155980502437, 6756.831079223059, 6762.526623136333, 6473.449756770728, 6490.7992021476175, 6523.510736694672, 6526.836829079311, 6547.723193977129], "value": 5175.6641241910165, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [901.5582364641326, 912.6714382822183, 920.7145784806711, 926.4811705444947, 939.6100618243429, 19347.322843700473, 19888.105035273413, 19897.502562435948, 19899.701504477445, 20968.475788105305, 21810.127594896185, 22571.54856902861, 22589.322320330375, 22752.431189220235, 22754.31737047887, 994.796066806303, 1009.9343542669703, 1010.434156872154, 1010.9292349535535, 1016.6390931219422, 973.4159504297401, 1008.5511947183506, 1009.848522721594, 1010.7271036820055, 1011.1506334678868, 1069.5561342043043, 1069.9090577300938, 1070.8661417322835, 1071.587438836046, 1071.9624813131543, 1066.9039786419182, 1069.4759567811793, 1069.58820853971, 1070.1599244593035, 1074.1997212077013, 782.4327362662082, 782.5422128055095, 782.9451938364317, 783.2637320918376, 783.4594130050247, 20100.29648962294, 20105.79894201054, 20438.386848394573, 20453.48866278355, 20490.897545512176, 21078.26226082095, 21545.1381944722, 21733.565328693494, 22035.889820550794, 22046.448838778997, 17614.11929403525, 17628.735568966476, 17701.557461063447, 18022.90988545049, 18220.63558728818, 3790.9810450947843, 3832.9233415331814, 3847.365184744514, 3848.8845334639727, 3859.4982652255308, 133021.33489332587, 133950.3302483491, 136742.0, 136861.0, 138011.0, 16585.917070414813, 16684.916575417286, 17075.658486830194, 17121.91439042797, 17156.828431715774, 30925.072247832602, 30981.69018309833, 31041.689583104333, 31474.37051258962, 31533.52699709506, 7745.8450830983065, 8214.178582141738, 8840.734777956672, 9249.722508324761, 9346.485943273185, 4445.644348452116, 4477.70894891831, 4490.775461226991, 4493.168763778712, 4502.099580083931, 5245.685258884403, 5335.226392173113, 5339.5728341732565, 5351.839444816662, 5390.407055223922, 1037.2790910317408, 1050.1231471721046, 1060.6181774561153, 1072.651388298806, 1073.897979691928, 605.7365046204915, 611.5596770325359, 615.602936106209, 617.7745123030152, 636.1793286660156, 8920.553972301359, 9390.750111140522, 9503.904960950302, 9655.613775449045, 10149.695509134737, 640.814163892466, 651.4625434016955, 662.6918482905376, 663.6217356107069, 664.727461740684, 4475.686701930898, 4477.3731677565165, 4488.865334039984, 4502.617277531381, 4581.2440947243995, 110424.0, 112068.0, 115416.0, 116988.0, 117344.0, 95615.52192238995, 96027.51986240024, 96518.51740741251, 97464.51267743616, 99639.0, 83959.58020209982, 86782.56608716917, 87162.56418717866, 87921.56039219763, 89134.0, 12002.63992080239, 12872.806907896576, 12918.031147664151, 13046.152000119962, 13171.143875648053, 36205.27589448249, 39964.60035399609, 40430.595694042684, 40833.387499186945, 41903.16193676049, 50591.49408505868, 51096.48903511066, 51710.22434663558, 51883.74058129685, 53121.734391327795, 17898.5525361867, 17919.820801792335, 18073.548161296072, 18442.35451759182, 18583.721244181077, 19968.600627987067, 20108.195672172948, 20203.292884748964, 20342.59314813725, 20422.387328380173, 18129.0, 18446.81553184451, 18495.53761155928, 18593.814061859208, 18615.255389784255, 17473.47579572615, 17561.209745561224, 17569.736453952944, 17593.384231551834, 17654.823451765318, 16645.667086658443, 16744.581385465463, 16808.327666893187, 16817.579560511083, 16841.915790420968, 12212.32832194238, 12321.199122057038, 12510.18683785551, 12586.18189817659, 12588.118831681695, 13460.125091868997, 13673.179609223478, 13727.750415425571, 13778.724425511635, 13804.102733322703], "value": 9046.55161771069, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1483.1000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 106.0, 99.0, 107.0, 106.0, 107.0, 110.0, 98.0, 108.0, 106.0], "value": 104.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 117.0, 147.0, 166.0, 115.0, 148.0, 142.0, 115.0, 172.0, 117.0], "value": 138.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 167.0, 164.0, 164.0, 165.0, 162.0, 161.0, 178.0, 168.0, 164.0], "value": 165.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 76.0, 182.0, 78.0, 75.0, 74.0, 73.0, 170.0, 75.0], "value": 95.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 139.0, 79.0, 79.0, 79.0, 128.0, 79.0, 79.0, 131.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 116.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 113.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 150.0, 148.0, 148.0, 150.0, 149.0, 148.0, 148.0, 149.0], "value": 149.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 95.0, 93.0, 94.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 56.0, 56.0, 52.0, 54.0, 53.0, 52.0, 53.0, 52.0, 55.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 40.0, 39.0, 39.0, 38.0, 38.0, 39.0, 45.0, 39.0, 38.0], "value": 39.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 74.0, 76.0, 74.0, 76.0, 75.0, 73.0, 74.0, 73.0], "value": 74.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 121.0, 120.0, 122.0, 122.0, 119.0, 116.0, 119.0, 116.0, 162.0], "value": 123.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 171.0, 170.0, 169.0, 176.0, 175.0, 167.0, 166.0, 171.0, 168.0], "value": 170.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 63.0, 64.0, 62.0, 63.0, 62.0, 63.0, 74.0, 63.0], "value": 64.9, "unit": "score"}]}]} 11:46:00 INFO - Return code: 0 11:46:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:46:00 INFO - # TBPL SUCCESS # 11:46:00 INFO - Running post-action listener: _package_coverage_data 11:46:00 INFO - Running post-action listener: _resource_record_post_action 11:46:00 INFO - [mozharness: 2017-09-05 18:46:00.171386Z] Finished run-tests step (success) 11:46:00 INFO - Running post-run listener: _resource_record_post_run 11:46:00 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 5533696; Write bytes: 408551424; Read time: 2260; Write time: 297072 11:46:00 INFO - TinderboxPrint: CPU usage
13.1% 11:46:00 INFO - TinderboxPrint: I/O read bytes / time
5,533,696 / 2,260 11:46:00 INFO - TinderboxPrint: I/O write bytes / time
408,551,424 / 297,072 11:46:00 INFO - TinderboxPrint: CPU idle
8,248.7 (86.9%) 11:46:00 INFO - TinderboxPrint: CPU user
1,193.2 (12.6%) 11:46:00 INFO - TinderboxPrint: Swap in / out
0 / 0 11:46:00 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 120324096; Read time: 212; Write time: 93540 11:46:00 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:46:00 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 3784704; Write bytes: 288227328; Read time: 1972; Write time: 203532 11:46:00 INFO - Running post-run listener: _upload_blobber_files 11:46:00 INFO - Blob upload gear active. 11:46:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:46:00 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 11:46:00 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'] 11:46:00 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 11:46:01 INFO - (blobuploader) - INFO - Open directory for files ... 11:46:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:46:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:46:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:02 INFO - (blobuploader) - INFO - Done attempting. 11:46:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:46:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:46:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:03 INFO - (blobuploader) - INFO - Done attempting. 11:46:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:46:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:46:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:04 INFO - (blobuploader) - INFO - Done attempting. 11:46:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:46:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:46:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:12 INFO - (blobuploader) - INFO - Done attempting. 11:46:12 INFO - (blobuploader) - INFO - Iteration through files over. 11:46:12 INFO - Return code: 0 11:46:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:46:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:46:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a0055700c1e0115c281cacf446d2e1544c5739c285ad4bd8db6c3ad3202565ecd6bad159a5f61180d53d9808b7b497d4487550881cbd6ec8be05ae925713406", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e8b58f9351690ace3ec3cb7d69769cb24cea44a9955ee4ec391435a5c99046cb89d912a2806effb7692ad732a3033e9906a55efb19fd5efc96604a948a2c5a9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd20c0e5317b42f998626630fd0452281edea7c9aee085dd24406b89f2efe097e51ca5cfdf04259fca8021c5669db824c05bcb4ffa6100cbf2e6c33a680f60ca", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c00841cb8a88065bef13868d4e80a904b28e8440b3a6d2b614103d76f476e65113ee553bc66e6251c9a968b1097fecaea25c54f87268897e260ff18bdb10cf7e"} 11:46:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:46:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:46:12 INFO - Contents: 11:46:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a0055700c1e0115c281cacf446d2e1544c5739c285ad4bd8db6c3ad3202565ecd6bad159a5f61180d53d9808b7b497d4487550881cbd6ec8be05ae925713406", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e8b58f9351690ace3ec3cb7d69769cb24cea44a9955ee4ec391435a5c99046cb89d912a2806effb7692ad732a3033e9906a55efb19fd5efc96604a948a2c5a9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd20c0e5317b42f998626630fd0452281edea7c9aee085dd24406b89f2efe097e51ca5cfdf04259fca8021c5669db824c05bcb4ffa6100cbf2e6c33a680f60ca", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c00841cb8a88065bef13868d4e80a904b28e8440b3a6d2b614103d76f476e65113ee553bc66e6251c9a968b1097fecaea25c54f87268897e260ff18bdb10cf7e"} 11:46:12 INFO - Running post-run listener: copy_logs_to_upload_dir 11:46:12 INFO - Copying logs to upload dir... 11:46:12 INFO - mkdir: /builds/slave/test/build/upload/logs 11:46:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.137506 ========= master_lag: 0.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-09-05 11:46:12.570921) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-05 11:46:12.574835) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a0055700c1e0115c281cacf446d2e1544c5739c285ad4bd8db6c3ad3202565ecd6bad159a5f61180d53d9808b7b497d4487550881cbd6ec8be05ae925713406", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e8b58f9351690ace3ec3cb7d69769cb24cea44a9955ee4ec391435a5c99046cb89d912a2806effb7692ad732a3033e9906a55efb19fd5efc96604a948a2c5a9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd20c0e5317b42f998626630fd0452281edea7c9aee085dd24406b89f2efe097e51ca5cfdf04259fca8021c5669db824c05bcb4ffa6100cbf2e6c33a680f60ca", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c00841cb8a88065bef13868d4e80a904b28e8440b3a6d2b614103d76f476e65113ee553bc66e6251c9a968b1097fecaea25c54f87268897e260ff18bdb10cf7e"} build_url:https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011336 build_url: 'https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a0055700c1e0115c281cacf446d2e1544c5739c285ad4bd8db6c3ad3202565ecd6bad159a5f61180d53d9808b7b497d4487550881cbd6ec8be05ae925713406", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e8b58f9351690ace3ec3cb7d69769cb24cea44a9955ee4ec391435a5c99046cb89d912a2806effb7692ad732a3033e9906a55efb19fd5efc96604a948a2c5a9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd20c0e5317b42f998626630fd0452281edea7c9aee085dd24406b89f2efe097e51ca5cfdf04259fca8021c5669db824c05bcb4ffa6100cbf2e6c33a680f60ca", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c00841cb8a88065bef13868d4e80a904b28e8440b3a6d2b614103d76f476e65113ee553bc66e6251c9a968b1097fecaea25c54f87268897e260ff18bdb10cf7e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-05 11:46:12.605986) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 11:46:12.606407) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1504631622.777174-1055260398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004484 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 11:46:12.688282) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-05 11:46:12.690625) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-05 11:46:12.690917) ========= ========= Total master_lag: 0.30 =========