builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-037 starttime: 1506626270.17 results: success (0) revision: 1c3b6791f9433c7cf8d6ecb453c9277fd99a5b4a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-28 12:17:50.172822) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-28 12:17:50.173239) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-28 12:17:50.173545) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-28 12:17:50.641120) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 12:17:50.641405) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-28 12:17:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.2M=0s 2017-09-28 12:17:51 (46.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120191 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 12:17:50.786454) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 12:17:50.786753) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018324 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 12:17:50.857496) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-28 12:17:50.857822) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 1c3b6791f9433c7cf8d6ecb453c9277fd99a5b4a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 1c3b6791f9433c7cf8d6ecb453c9277fd99a5b4a --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=56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-28 12:17:51,377 truncating revision to first 12 chars 2017-09-28 12:17:51,377 Setting DEBUG logging. 2017-09-28 12:17:51,377 attempt 1/10 2017-09-28 12:17:51,377 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/1c3b6791f943?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-28 12:17:52,083 unpacking tar archive at: mozilla-beta-1c3b6791f943/testing/mozharness/ program finished with exit code 0 elapsedTime=1.009686 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-28 12:17:51.896099) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-28 12:17:51.896498) ========= script_repo_revision: 1c3b6791f9433c7cf8d6ecb453c9277fd99a5b4a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-28 12:17:51.896953) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-28 12:17:51.897255) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-28 12:17:51.910867) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2017-09-28 12:17:51.911315) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 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', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-Non-PGO'] 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=56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:17:52 INFO - MultiFileLogger online at 20170928 12:17:52 in /builds/slave/test 12:17:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 12:17:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:17:52 INFO - {'append_to_log': False, 12:17:52 INFO - 'base_work_dir': '/builds/slave/test', 12:17:52 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 12:17:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:17:52 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 12:17:52 INFO - 'buildbot_json_path': 'buildprops.json', 12:17:52 INFO - 'code_coverage': False, 12:17:52 INFO - 'config_files': ('talos/linux_config.py',), 12:17:52 INFO - 'default_actions': ('clobber', 12:17:52 INFO - 'read-buildbot-config', 12:17:52 INFO - 'download-and-extract', 12:17:52 INFO - 'populate-webroot', 12:17:52 INFO - 'create-virtualenv', 12:17:52 INFO - 'install', 12:17:52 INFO - 'setup-mitmproxy', 12:17:52 INFO - 'run-tests'), 12:17:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:17:52 INFO - 'disable_ccov_upload': False, 12:17:52 INFO - 'disable_stylo': False, 12:17:52 INFO - 'download_minidump_stackwalk': True, 12:17:52 INFO - 'download_symbols': 'ondemand', 12:17:52 INFO - 'enable_stylo': False, 12:17:52 INFO - 'enable_webrender': False, 12:17:52 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 12:17:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:17:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:17:52 INFO - 'gecko_profile': False, 12:17:52 INFO - 'gecko_profile_interval': 0, 12:17:52 INFO - 'installer_path': 'installer.exe', 12:17:52 INFO - 'log_level': 'info', 12:17:52 INFO - 'log_name': 'talos', 12:17:52 INFO - 'log_to_console': True, 12:17:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:17:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:17:52 INFO - 'opt_config_files': (), 12:17:52 INFO - 'pip_index': False, 12:17:52 INFO - 'suite': 'dromaeojs-e10s', 12:17:52 INFO - 'system_bits': '32', 12:17:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:17:52 INFO - 'title': 'talos-linux64-ix-037', 12:17:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:17:52 INFO - 'use_talos_json': True, 12:17:52 INFO - 'verify': 'False', 12:17:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:17:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:17:52 INFO - 'work_dir': 'build'} 12:17:52 INFO - [mozharness: 2017-09-28 19:17:52.622755Z] Running clobber step. 12:17:52 INFO - Running pre-action listener: _resource_record_pre_action 12:17:52 INFO - Running main action method: clobber 12:17:52 INFO - rmtree: /builds/slave/test/build 12:17:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:17:53 INFO - Running post-action listener: _resource_record_post_action 12:17:53 INFO - [mozharness: 2017-09-28 19:17:53.580193Z] Finished clobber step (success) 12:17:53 INFO - [mozharness: 2017-09-28 19:17:53.580290Z] Running read-buildbot-config step. 12:17:53 INFO - Running pre-action listener: _resource_record_pre_action 12:17:53 INFO - Running main action method: read_buildbot_config 12:17:53 INFO - Using buildbot properties: 12:17:53 INFO - { 12:17:53 INFO - "project": "", 12:17:53 INFO - "product": "firefox", 12:17:53 INFO - "who": "ryanvm@gmail.com", 12:17:53 INFO - "installer_path": "public/build/target.tar.bz2", 12:17:53 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 12:17:53 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta", 12:17:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs-e10s", 12:17:53 INFO - "stage_platform": "linux64", 12:17:53 INFO - "basedir": "/builds/slave/test", 12:17:53 INFO - "buildnumber": 136, 12:17:53 INFO - "platform": "ubuntu64_hw", 12:17:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:17:53 INFO - "slavebuilddir": "test", 12:17:53 INFO - "taskId": "dUnIFzHWQzeKP_qBS2_Qdw", 12:17:53 INFO - "branch": "mozilla-beta", 12:17:53 INFO - "script_repo_revision": "production", 12:17:53 INFO - "revision": "1c3b6791f9433c7cf8d6ecb453c9277fd99a5b4a", 12:17:53 INFO - "slavename": "talos-linux64-ix-037", 12:17:53 INFO - "repo_path": "releases/mozilla-beta" 12:17:53 INFO - } 12:17:53 INFO - Finding installer, test and symbols from parent task. 12:17:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dUnIFzHWQzeKP_qBS2_Qdw'}, attempt #1 12:17:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dUnIFzHWQzeKP_qBS2_Qdw'}, attempt #1 12:17:54 INFO - Task dependencies: D8Pxxm9sS2eD_9v1y_8bOA 12:17:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA'}, attempt #1 12:17:55 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA/artifacts/public/build/target.tar.bz2 12:17:55 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA/artifacts/public/build/target.test_packages.json 12:17:55 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA/artifacts/public/build/target.crashreporter-symbols.zip 12:17:55 INFO - Running post-action listener: _resource_record_post_action 12:17:55 INFO - [mozharness: 2017-09-28 19:17:55.202487Z] Finished read-buildbot-config step (success) 12:17:55 INFO - [mozharness: 2017-09-28 19:17:55.202705Z] Running download-and-extract step. 12:17:55 INFO - Running pre-action listener: _resource_record_pre_action 12:17:55 INFO - Running main action method: download_and_extract 12:17:55 INFO - mkdir: /builds/slave/test/build/tests 12:17: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')]} 12:17:55 INFO - trying https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA/artifacts/public/build/target.test_packages.json 12:17:55 INFO - Downloading https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:17:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:17:55 INFO - Downloaded 989 bytes. 12:17:55 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:17:55 INFO - Using the following test package requirements: 12:17:55 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:17:55 INFO - u'common': [u'target.common.tests.zip'], 12:17:55 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:17:55 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:17:55 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:17:55 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:17:55 INFO - u'mozbase': [u'target.common.tests.zip'], 12:17:55 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:17:55 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:17:55 INFO - u'web-platform': [u'target.common.tests.zip', 12:17:55 INFO - u'target.web-platform.tests.tar.gz'], 12:17:55 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:17:55 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 12:17:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA/artifacts/public/build/target.common.tests.zip 12:17:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:17:55 INFO - Fetch https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA/artifacts/public/build/target.common.tests.zip into memory 12:17:57 INFO - Content-Length response header: 55176743 12:17:57 INFO - Bytes received: 55176743 12:18:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA/artifacts/public/build/target.talos.tests.zip 12:18:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:18:08 INFO - Fetch https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA/artifacts/public/build/target.talos.tests.zip into memory 12:18:09 INFO - Content-Length response header: 14509184 12:18:09 INFO - Bytes received: 14509184 12:18:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:09 INFO - trying https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA/artifacts/public/build/target.tar.bz2 12:18:09 INFO - Downloading https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:18:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:18:10 INFO - Downloaded 53577051 bytes. 12:18:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA/artifacts/public/build/target.tar.bz2 12:18:10 INFO - mkdir: /builds/slave/test/properties 12:18:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:18:10 INFO - Writing to file /builds/slave/test/properties/build_url 12:18:10 INFO - Contents: 12:18:10 INFO - build_url:https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA/artifacts/public/build/target.tar.bz2 12:18:10 INFO - Running post-action listener: _resource_record_post_action 12:18:10 INFO - Running post-action listener: find_tests_for_verification 12:18:10 INFO - Running post-action listener: set_extra_try_arguments 12:18:10 INFO - [mozharness: 2017-09-28 19:18:10.630017Z] Finished download-and-extract step (success) 12:18:10 INFO - [mozharness: 2017-09-28 19:18:10.630115Z] Running populate-webroot step. 12:18:10 INFO - Running pre-action listener: _resource_record_pre_action 12:18:10 INFO - Running main action method: populate_webroot 12:18:10 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:18:10 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:18:10 INFO - 'tests': ['tresize', 12:18:10 INFO - 'tcanvasmark']}, 12:18:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:18:10 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:18:10 INFO - 'tests': ['dromaeo_css', 12:18:10 INFO - 'kraken']}, 12:18:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:18:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:18:10 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:18:10 INFO - 'talos_options': ['--disable-stylo'], 12:18:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:18:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:18:10 INFO - 'tests': ['damp', 'tps']}, 12:18:10 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:18:10 INFO - 'talos_options': ['--disable-stylo'], 12:18:10 INFO - 'tests': ['damp', 'tps']}, 12:18:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:18:10 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:18:10 INFO - 'tests': ['dromaeo_dom']}, 12:18:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:18:10 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:18:10 INFO - 'tests': ['basic_compositor_video', 12:18:10 INFO - 'glvideo']}, 12:18:10 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:18:10 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:18:10 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:18:10 INFO - 'talos_options': ['--disable-stylo'], 12:18:10 INFO - 'tests': ['ts_paint_webext', 12:18:10 INFO - 'tp5o_webext']}, 12:18:10 INFO - 'other-e10s': {'tests': ['a11yr', 12:18:10 INFO - 'ts_paint', 12:18:10 INFO - 'tpaint', 12:18:10 INFO - 'sessionrestore', 12:18:10 INFO - 'sessionrestore_many_windows', 12:18:10 INFO - 'sessionrestore_no_auto_restore', 12:18:10 INFO - 'tabpaint']}, 12:18:10 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:18:10 INFO - 'tests': ['a11yr', 12:18:10 INFO - 'ts_paint', 12:18:10 INFO - 'tpaint', 12:18:10 INFO - 'sessionrestore', 12:18:10 INFO - 'sessionrestore_many_windows', 12:18:10 INFO - 'sessionrestore_no_auto_restore', 12:18:10 INFO - 'tabpaint']}, 12:18:10 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 12:18:10 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 12:18:10 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:18:10 INFO - 'tests': ['perf_reftest_singletons']}, 12:18:10 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:18:10 INFO - 'tests': ['bloom_basic']}, 12:18:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:18:10 INFO - 'tsvgr_opacity', 12:18:10 INFO - 'tart', 12:18:10 INFO - 'tscrollx', 12:18:10 INFO - 'tsvg_static']}, 12:18:10 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:18:10 INFO - 'tests': ['tsvgx', 12:18:10 INFO - 'tsvgr_opacity', 12:18:10 INFO - 'tart', 12:18:10 INFO - 'tscrollx', 12:18:10 INFO - 'tsvg_static']}, 12:18:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:18:10 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:18:10 INFO - 'talos_options': ['--disable-stylo'], 12:18:10 INFO - 'tests': ['tp5o']}, 12:18:10 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:18:10 INFO - 'talos_options': ['--mitmproxy', 12:18:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:18:10 INFO - '--firstNonBlankPaint'], 12:18:10 INFO - 'tests': ['tp6_google', 12:18:10 INFO - 'tp6_youtube', 12:18:10 INFO - 'tp6_amazon', 12:18:10 INFO - 'tp6_facebook']}, 12:18:10 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:18:10 INFO - 'talos_options': ['--disable-stylo', 12:18:10 INFO - '--mitmproxy', 12:18:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:18:10 INFO - '--firstNonBlankPaint'], 12:18:10 INFO - 'tests': ['tp6_google', 12:18:10 INFO - 'tp6_youtube', 12:18:10 INFO - 'tp6_amazon', 12:18:10 INFO - 'tp6_facebook']}, 12:18:10 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:18:10 INFO - 'talos_options': ['--stylo-threads=1', 12:18:10 INFO - '--mitmproxy', 12:18:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:18:10 INFO - '--firstNonBlankPaint'], 12:18:10 INFO - 'tests': ['tp6_google', 12:18:10 INFO - 'tp6_youtube', 12:18:10 INFO - 'tp6_amazon', 12:18:10 INFO - 'tp6_facebook']}, 12:18:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:18:10 INFO - 'talos_options': ['--xperf_path', 12:18:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:18:10 INFO - 'tests': ['tp5n']}, 12:18:10 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:18:10 INFO - 'talos_options': ['--disable-stylo', 12:18:10 INFO - '--xperf_path', 12:18:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:18:10 INFO - 'tests': ['tp5n']}}} 12:18:10 INFO - Running post-action listener: _resource_record_post_action 12:18:10 INFO - [mozharness: 2017-09-28 19:18:10.645077Z] Finished populate-webroot step (success) 12:18:10 INFO - [mozharness: 2017-09-28 19:18:10.645166Z] Running create-virtualenv step. 12:18:10 INFO - Running pre-action listener: _resource_record_pre_action 12:18:10 INFO - Running main action method: create_virtualenv 12:18:10 INFO - Creating virtualenv /builds/slave/test/build/venv 12:18:10 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:18:10 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 12:18:10 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:18:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:18:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:18:12 INFO - Installing distribute.............................................................................................................................................................................................done. 12:18:12 INFO - Installing pip...............done. 12:18:12 INFO - Return code: 0 12:18:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:18:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:18:12 INFO - Reading from file tmpfile_stdout 12:18:12 INFO - Output received: 12:18:12 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 12:18:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:18:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:12 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 0x13f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x175ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175f090>, '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 0x175fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1759f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', '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 12:18:12 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 12:18:12 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 12:18:12 INFO - Using env: {'DISPLAY': ':0', 12:18:12 INFO - 'HOME': '/home/cltbld', 12:18:12 INFO - 'LANG': 'en_US.UTF-8', 12:18:12 INFO - 'LANGUAGE': 'en_US:en', 12:18:12 INFO - 'LOGNAME': 'cltbld', 12:18:12 INFO - 'MAIL': '/var/mail/cltbld', 12:18:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:12 INFO - 'MOZ_NO_REMOTE': '1', 12:18:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:18:12 INFO - 'NO_EM_RESTART': '1', 12:18:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:18:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:12 INFO - 'PWD': '/builds/slave/test', 12:18:12 INFO - 'SHELL': '/bin/bash', 12:18:12 INFO - 'SHLVL': '1', 12:18:12 INFO - 'TERM': 'linux', 12:18:12 INFO - 'TMOUT': '86400', 12:18:12 INFO - 'USER': 'cltbld', 12:18:12 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', 12:18:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:12 INFO - '_': '/tools/buildbot/bin/python'} 12:18:14 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:18:14 INFO - Downloading/unpacking pip>=1.5 12:18:14 INFO - Running setup.py egg_info for package pip 12:18:14 INFO - warning: no files found matching 'pip/cacert.pem' 12:18:14 INFO - warning: no files found matching '*.html' under directory 'docs' 12:18:14 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:18:14 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:18:14 INFO - Installing collected packages: pip 12:18:14 INFO - Found existing installation: pip 1.1 12:18:14 INFO - Uninstalling pip: 12:18:14 INFO - Successfully uninstalled pip 12:18:14 INFO - Running setup.py install for pip 12:18:14 INFO - warning: no files found matching 'pip/cacert.pem' 12:18:14 INFO - warning: no files found matching '*.html' under directory 'docs' 12:18:14 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:18:14 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:18:14 INFO - Installing pip script to /builds/slave/test/build/venv/bin 12:18:14 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 12:18:14 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 12:18:14 INFO - Successfully installed pip 12:18:14 INFO - Cleaning up... 12:18:14 INFO - Return code: 0 12:18:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:18:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:14 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 0x13f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x175ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175f090>, '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 0x175fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1759f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', '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 12:18:14 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 12:18:14 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 12:18:14 INFO - Using env: {'DISPLAY': ':0', 12:18:14 INFO - 'HOME': '/home/cltbld', 12:18:14 INFO - 'LANG': 'en_US.UTF-8', 12:18:14 INFO - 'LANGUAGE': 'en_US:en', 12:18:14 INFO - 'LOGNAME': 'cltbld', 12:18:14 INFO - 'MAIL': '/var/mail/cltbld', 12:18:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:14 INFO - 'MOZ_NO_REMOTE': '1', 12:18:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:18:14 INFO - 'NO_EM_RESTART': '1', 12:18:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:18:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:14 INFO - 'PWD': '/builds/slave/test', 12:18:14 INFO - 'SHELL': '/bin/bash', 12:18:14 INFO - 'SHLVL': '1', 12:18:14 INFO - 'TERM': 'linux', 12:18:14 INFO - 'TMOUT': '86400', 12:18:14 INFO - 'USER': 'cltbld', 12:18:14 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', 12:18:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:14 INFO - '_': '/tools/buildbot/bin/python'} 12:18:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:14 INFO - Downloading/unpacking psutil>=3.1.1 12:18:14 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 12:18:14 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 12:18:14 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 12:18:14 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 12:18:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:18:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:18:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:18:15 INFO - Installing collected packages: psutil 12:18:15 INFO - Running setup.py install for psutil 12:18:15 INFO - building 'psutil._psutil_linux' extension 12:18:15 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 12:18:16 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 12:18:16 INFO - building 'psutil._psutil_posix' extension 12:18:16 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 12:18:17 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 12:18:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:18:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:18:17 INFO - Successfully installed psutil 12:18:17 INFO - Cleaning up... 12:18:17 INFO - Return code: 0 12:18:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:18:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x175ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175f090>, '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 0x175fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1759f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', '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 12:18:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:18:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:18:17 INFO - Using env: {'DISPLAY': ':0', 12:18:17 INFO - 'HOME': '/home/cltbld', 12:18:17 INFO - 'LANG': 'en_US.UTF-8', 12:18:17 INFO - 'LANGUAGE': 'en_US:en', 12:18:17 INFO - 'LOGNAME': 'cltbld', 12:18:17 INFO - 'MAIL': '/var/mail/cltbld', 12:18:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:17 INFO - 'MOZ_NO_REMOTE': '1', 12:18:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:18:17 INFO - 'NO_EM_RESTART': '1', 12:18:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:18:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:17 INFO - 'PWD': '/builds/slave/test', 12:18:17 INFO - 'SHELL': '/bin/bash', 12:18:17 INFO - 'SHLVL': '1', 12:18:17 INFO - 'TERM': 'linux', 12:18:17 INFO - 'TMOUT': '86400', 12:18:17 INFO - 'USER': 'cltbld', 12:18:17 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', 12:18:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:17 INFO - '_': '/tools/buildbot/bin/python'} 12:18:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:18:17 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 12:18:17 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 12:18:17 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 12:18:17 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 12:18:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:18:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:18:18 INFO - Installing collected packages: mozsystemmonitor 12:18:18 INFO - Running setup.py install for mozsystemmonitor 12:18:18 INFO - Successfully installed mozsystemmonitor 12:18:18 INFO - Cleaning up... 12:18:18 INFO - Return code: 0 12:18:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:18:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:18 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 0x13f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x175ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175f090>, '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 0x175fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1759f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', '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 12:18:18 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 12:18:18 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 12:18:18 INFO - Using env: {'DISPLAY': ':0', 12:18:18 INFO - 'HOME': '/home/cltbld', 12:18:18 INFO - 'LANG': 'en_US.UTF-8', 12:18:18 INFO - 'LANGUAGE': 'en_US:en', 12:18:18 INFO - 'LOGNAME': 'cltbld', 12:18:18 INFO - 'MAIL': '/var/mail/cltbld', 12:18:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:18 INFO - 'MOZ_NO_REMOTE': '1', 12:18:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:18:18 INFO - 'NO_EM_RESTART': '1', 12:18:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:18:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:18 INFO - 'PWD': '/builds/slave/test', 12:18:18 INFO - 'SHELL': '/bin/bash', 12:18:18 INFO - 'SHLVL': '1', 12:18:18 INFO - 'TERM': 'linux', 12:18:18 INFO - 'TMOUT': '86400', 12:18:18 INFO - 'USER': 'cltbld', 12:18:18 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', 12:18:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:18 INFO - '_': '/tools/buildbot/bin/python'} 12:18:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:18 INFO - Downloading/unpacking jsonschema==2.5.1 12:18:18 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 12:18:18 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 12:18:18 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 12:18:18 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 12:18:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:18:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:18:20 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 12:18:20 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 12:18:20 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 12:18:20 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 12:18:20 INFO - Downloading functools32-3.2.3-2.tar.gz 12:18:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:18:20 INFO - warning: no files found matching '*.txt' 12:18:20 INFO - no previously-included directories found matching 'build' 12:18:20 INFO - no previously-included directories found matching 'dist' 12:18:20 INFO - no previously-included directories found matching '.git*' 12:18:20 INFO - Installing collected packages: jsonschema, functools32 12:18:20 INFO - Running setup.py install for functools32 12:18:20 INFO - warning: no files found matching '*.txt' 12:18:20 INFO - no previously-included directories found matching 'build' 12:18:20 INFO - no previously-included directories found matching 'dist' 12:18:20 INFO - no previously-included directories found matching '.git*' 12:18:20 INFO - Successfully installed jsonschema functools32 12:18:20 INFO - Cleaning up... 12:18:20 INFO - Return code: 0 12:18:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:20 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 0x13f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x175ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175f090>, '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 0x175fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1759f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', '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 12:18:20 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 12:18:20 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 12:18:20 INFO - Using env: {'DISPLAY': ':0', 12:18:20 INFO - 'HOME': '/home/cltbld', 12:18:20 INFO - 'LANG': 'en_US.UTF-8', 12:18:20 INFO - 'LANGUAGE': 'en_US:en', 12:18:20 INFO - 'LOGNAME': 'cltbld', 12:18:20 INFO - 'MAIL': '/var/mail/cltbld', 12:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:20 INFO - 'MOZ_NO_REMOTE': '1', 12:18:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:18:20 INFO - 'NO_EM_RESTART': '1', 12:18:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:18:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:20 INFO - 'PWD': '/builds/slave/test', 12:18:20 INFO - 'SHELL': '/bin/bash', 12:18:20 INFO - 'SHLVL': '1', 12:18:20 INFO - 'TERM': 'linux', 12:18:20 INFO - 'TMOUT': '86400', 12:18:20 INFO - 'USER': 'cltbld', 12:18:20 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', 12:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:20 INFO - '_': '/tools/buildbot/bin/python'} 12:18:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:18:20 INFO - Cleaning up... 12:18:20 INFO - Return code: 0 12:18:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:20 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 0x13f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x175ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175f090>, '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 0x175fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1759f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', '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 12:18:20 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 12:18:20 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 12:18:20 INFO - Using env: {'DISPLAY': ':0', 12:18:20 INFO - 'HOME': '/home/cltbld', 12:18:20 INFO - 'LANG': 'en_US.UTF-8', 12:18:20 INFO - 'LANGUAGE': 'en_US:en', 12:18:20 INFO - 'LOGNAME': 'cltbld', 12:18:20 INFO - 'MAIL': '/var/mail/cltbld', 12:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:20 INFO - 'MOZ_NO_REMOTE': '1', 12:18:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:18:20 INFO - 'NO_EM_RESTART': '1', 12:18:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:18:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:20 INFO - 'PWD': '/builds/slave/test', 12:18:20 INFO - 'SHELL': '/bin/bash', 12:18:20 INFO - 'SHLVL': '1', 12:18:20 INFO - 'TERM': 'linux', 12:18:20 INFO - 'TMOUT': '86400', 12:18:20 INFO - 'USER': 'cltbld', 12:18:20 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', 12:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:20 INFO - '_': '/tools/buildbot/bin/python'} 12:18:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:20 INFO - Downloading/unpacking blobuploader==1.2.4 12:18:20 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 12:18:20 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 12:18:20 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 12:18:20 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 12:18:21 INFO - Downloading blobuploader-1.2.4.tar.gz 12:18:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:18:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:18:22 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 12:18:22 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 12:18:22 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 12:18:22 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 12:18:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:18:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:18:22 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 12:18:22 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 12:18:22 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 12:18:22 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 12:18:22 INFO - Downloading docopt-0.6.1.tar.gz 12:18:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:18:22 INFO - Installing collected packages: blobuploader, requests, docopt 12:18:22 INFO - Running setup.py install for blobuploader 12:18:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:18:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:18:22 INFO - Running setup.py install for requests 12:18:23 INFO - Running setup.py install for docopt 12:18:23 INFO - Successfully installed blobuploader requests docopt 12:18:23 INFO - Cleaning up... 12:18:23 INFO - Return code: 0 12:18:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:23 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 0x13f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x175ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175f090>, '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 0x175fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1759f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', '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 12:18:23 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 12:18:23 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 12:18:23 INFO - Using env: {'DISPLAY': ':0', 12:18:23 INFO - 'HOME': '/home/cltbld', 12:18:23 INFO - 'LANG': 'en_US.UTF-8', 12:18:23 INFO - 'LANGUAGE': 'en_US:en', 12:18:23 INFO - 'LOGNAME': 'cltbld', 12:18:23 INFO - 'MAIL': '/var/mail/cltbld', 12:18:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:23 INFO - 'MOZ_NO_REMOTE': '1', 12:18:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:18:23 INFO - 'NO_EM_RESTART': '1', 12:18:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:18:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:23 INFO - 'PWD': '/builds/slave/test', 12:18:23 INFO - 'SHELL': '/bin/bash', 12:18:23 INFO - 'SHLVL': '1', 12:18:23 INFO - 'TERM': 'linux', 12:18:23 INFO - 'TMOUT': '86400', 12:18:23 INFO - 'USER': 'cltbld', 12:18:23 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', 12:18:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:23 INFO - '_': '/tools/buildbot/bin/python'} 12:18:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:18:23 INFO - Running setup.py (path:/tmp/pip-rS3iSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:18:23 INFO - Running setup.py (path:/tmp/pip-JgKfeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:18:23 INFO - Running setup.py (path:/tmp/pip-1nScVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:18:23 INFO - Running setup.py (path:/tmp/pip-3NZTWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:18:23 INFO - Running setup.py (path:/tmp/pip-bMmtnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:18:23 INFO - Running setup.py (path:/tmp/pip-1c0iuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:18:23 INFO - Running setup.py (path:/tmp/pip-1b0oU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:18:23 INFO - Running setup.py (path:/tmp/pip-BlElHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:18:23 INFO - Running setup.py (path:/tmp/pip-u7CujJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:18:24 INFO - Running setup.py (path:/tmp/pip-7daXv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:18:24 INFO - Running setup.py (path:/tmp/pip-q5wb0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:18:24 INFO - Running setup.py (path:/tmp/pip-jQP9q0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:18:24 INFO - Running setup.py (path:/tmp/pip-ugiqrz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:18:24 INFO - Running setup.py (path:/tmp/pip-1Uf4Uz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:18:24 INFO - Running setup.py (path:/tmp/pip-QPvTLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:18:24 INFO - Running setup.py (path:/tmp/pip-ZfaKZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:18:24 INFO - Running setup.py (path:/tmp/pip-RS5IAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:18:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:18:24 INFO - Running setup.py install for manifestparser 12:18:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:18:24 INFO - Running setup.py install for mozcrash 12:18:24 INFO - Running setup.py install for mozdebug 12:18:24 INFO - Running setup.py install for mozdevice 12:18:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:18:25 INFO - Running setup.py install for mozfile 12:18:25 INFO - Running setup.py install for mozhttpd 12:18:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:18:25 INFO - Running setup.py install for mozinfo 12:18:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:18:25 INFO - Running setup.py install for mozInstall 12:18:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:18:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:18:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:18:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:18:25 INFO - Running setup.py install for mozleak 12:18:25 INFO - Running setup.py install for mozlog 12:18:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:18:25 INFO - Running setup.py install for moznetwork 12:18:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:18:25 INFO - Running setup.py install for mozprocess 12:18:25 INFO - Running setup.py install for mozprofile 12:18:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:18:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:18:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:18:25 INFO - Running setup.py install for mozrunner 12:18:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:18:26 INFO - Running setup.py install for mozscreenshot 12:18:26 INFO - Running setup.py install for moztest 12:18:26 INFO - Running setup.py install for mozversion 12:18:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:18:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:18:26 INFO - Cleaning up... 12:18:26 INFO - Return code: 0 12:18:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:18:26 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')]} 12:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:26 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')]} 12:18:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:26 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 0x13f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x175ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175f090>, '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 0x175fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1759f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', '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 12:18:26 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 12:18:26 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 12:18:26 INFO - Using env: {'DISPLAY': ':0', 12:18:26 INFO - 'HOME': '/home/cltbld', 12:18:26 INFO - 'LANG': 'en_US.UTF-8', 12:18:26 INFO - 'LANGUAGE': 'en_US:en', 12:18:26 INFO - 'LOGNAME': 'cltbld', 12:18:26 INFO - 'MAIL': '/var/mail/cltbld', 12:18:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:26 INFO - 'MOZ_NO_REMOTE': '1', 12:18:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:18:26 INFO - 'NO_EM_RESTART': '1', 12:18:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:18:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:26 INFO - 'PWD': '/builds/slave/test', 12:18:26 INFO - 'SHELL': '/bin/bash', 12:18:26 INFO - 'SHLVL': '1', 12:18:26 INFO - 'TERM': 'linux', 12:18:26 INFO - 'TMOUT': '86400', 12:18:26 INFO - 'USER': 'cltbld', 12:18:26 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', 12:18:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:26 INFO - '_': '/tools/buildbot/bin/python'} 12:18:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:18:26 INFO - Running setup.py (path:/tmp/pip-ZnUMZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:18:26 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)) 12:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:18:26 INFO - Running setup.py (path:/tmp/pip-viDsTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:18:26 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)) 12:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:18:26 INFO - Running setup.py (path:/tmp/pip-fFRzG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:18:26 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)) 12:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:18:26 INFO - Running setup.py (path:/tmp/pip-bxIytO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:18:26 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)) 12:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:18:26 INFO - Running setup.py (path:/tmp/pip-dFNYDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:18:26 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)) 12:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:18:26 INFO - Running setup.py (path:/tmp/pip-GlC6yh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:18:27 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)) 12:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:18:27 INFO - Running setup.py (path:/tmp/pip-lmFOtW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:18:27 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)) 12:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:18:27 INFO - Running setup.py (path:/tmp/pip-fhkklT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:18:27 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)) 12:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:18:27 INFO - Running setup.py (path:/tmp/pip-j3iDzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:18:27 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)) 12:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:18:27 INFO - Running setup.py (path:/tmp/pip-vXL_iW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:18:27 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)) 12:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:18:27 INFO - Running setup.py (path:/tmp/pip-R5FVUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:18:27 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)) 12:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:18:27 INFO - Running setup.py (path:/tmp/pip-guqt0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:18:27 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)) 12:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:18:27 INFO - Running setup.py (path:/tmp/pip-IND8AL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:18:27 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)) 12:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:18:27 INFO - Running setup.py (path:/tmp/pip-9Ta5FF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:18:27 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)) 12:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:18:27 INFO - Running setup.py (path:/tmp/pip-reiMx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:18:27 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)) 12:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:18:27 INFO - Running setup.py (path:/tmp/pip-WqWvvt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:18:27 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)) 12:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:18:27 INFO - Running setup.py (path:/tmp/pip-PFAP7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:18:27 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)) 12:18:27 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)) 12:18:27 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)) 12:18:27 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)) 12:18:27 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)) 12:18:27 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)) 12:18:27 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)) 12:18:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:28 INFO - Downloading blessings-1.6.tar.gz 12:18:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:18:29 INFO - Installing collected packages: blessings 12:18:29 INFO - Running setup.py install for blessings 12:18:29 INFO - Successfully installed blessings 12:18:29 INFO - Cleaning up... 12:18:29 INFO - Return code: 0 12:18:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:18:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:18:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:18:29 INFO - Reading from file tmpfile_stdout 12:18:29 INFO - Current package versions: 12:18:29 INFO - argparse == 1.2.1 12:18:29 INFO - blessings == 1.6 12:18:29 INFO - blobuploader == 1.2.4 12:18:29 INFO - docopt == 0.6.1 12:18:29 INFO - functools32 == 3.2.3-2 12:18:29 INFO - jsonschema == 2.5.1 12:18:29 INFO - manifestparser == 1.1 12:18:29 INFO - mozInstall == 1.13 12:18:29 INFO - mozcrash == 1.0 12:18:29 INFO - mozdebug == 0.1 12:18:29 INFO - mozdevice == 0.51 12:18:29 INFO - mozfile == 1.2 12:18:29 INFO - mozhttpd == 0.7 12:18:29 INFO - mozinfo == 0.10 12:18:29 INFO - mozleak == 0.1 12:18:29 INFO - mozlog == 3.5 12:18:29 INFO - moznetwork == 0.27 12:18:29 INFO - mozprocess == 0.25 12:18:29 INFO - mozprofile == 0.28 12:18:29 INFO - mozrunner == 6.13 12:18:29 INFO - mozscreenshot == 0.1 12:18:29 INFO - mozsystemmonitor == 0.3 12:18:29 INFO - moztest == 0.8 12:18:29 INFO - mozversion == 1.4 12:18:29 INFO - psutil == 3.1.1 12:18:29 INFO - requests == 1.2.3 12:18:29 INFO - wsgiref == 0.1.2 12:18:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:18:29 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')]} 12:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:29 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')]} 12:18:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:29 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 0x13f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x175ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175f090>, '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 0x175fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1759f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', '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 12:18:29 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 12:18:29 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 12:18:29 INFO - Using env: {'DISPLAY': ':0', 12:18:29 INFO - 'HOME': '/home/cltbld', 12:18:29 INFO - 'LANG': 'en_US.UTF-8', 12:18:29 INFO - 'LANGUAGE': 'en_US:en', 12:18:29 INFO - 'LOGNAME': 'cltbld', 12:18:29 INFO - 'MAIL': '/var/mail/cltbld', 12:18:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:29 INFO - 'MOZ_NO_REMOTE': '1', 12:18:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:18:29 INFO - 'NO_EM_RESTART': '1', 12:18:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:18:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:29 INFO - 'PWD': '/builds/slave/test', 12:18:29 INFO - 'SHELL': '/bin/bash', 12:18:29 INFO - 'SHLVL': '1', 12:18:29 INFO - 'TERM': 'linux', 12:18:29 INFO - 'TMOUT': '86400', 12:18:29 INFO - 'USER': 'cltbld', 12:18:29 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', 12:18:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:29 INFO - '_': '/tools/buildbot/bin/python'} 12:18:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:29 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)) 12:18:29 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)) 12:18:29 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)) 12:18:29 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)) 12:18:29 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)) 12:18:29 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)) 12:18:29 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)) 12:18:29 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)) 12:18:29 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)) 12:18:29 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:18:30 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)) 12:18:30 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)) 12:18:30 INFO - Installing collected packages: simplejson 12:18:30 INFO - Running setup.py install for simplejson 12:18:30 INFO - building 'simplejson._speedups' extension 12:18:30 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 12:18:31 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 12:18:31 INFO - Successfully installed simplejson 12:18:31 INFO - Cleaning up... 12:18:31 INFO - Return code: 0 12:18:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:18:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:31 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 0x13f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x175ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175f090>, '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 0x175fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1759f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', '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 12:18:31 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 12:18:31 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 12:18:31 INFO - Using env: {'DISPLAY': ':0', 12:18:31 INFO - 'HOME': '/home/cltbld', 12:18:31 INFO - 'LANG': 'en_US.UTF-8', 12:18:31 INFO - 'LANGUAGE': 'en_US:en', 12:18:31 INFO - 'LOGNAME': 'cltbld', 12:18:31 INFO - 'MAIL': '/var/mail/cltbld', 12:18:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:31 INFO - 'MOZ_NO_REMOTE': '1', 12:18:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:18:31 INFO - 'NO_EM_RESTART': '1', 12:18:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:18:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:31 INFO - 'PWD': '/builds/slave/test', 12:18:31 INFO - 'SHELL': '/bin/bash', 12:18:31 INFO - 'SHLVL': '1', 12:18:31 INFO - 'TERM': 'linux', 12:18:31 INFO - 'TMOUT': '86400', 12:18:31 INFO - 'USER': 'cltbld', 12:18:31 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', 12:18:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:31 INFO - '_': '/tools/buildbot/bin/python'} 12:18:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:18:32 INFO - Cleaning up... 12:18:32 INFO - Return code: 0 12:18:32 INFO - Running post-action listener: _resource_record_post_action 12:18:32 INFO - Running post-action listener: _start_resource_monitoring 12:18:32 INFO - Starting resource monitoring. 12:18:32 INFO - [mozharness: 2017-09-28 19:18:32.162393Z] Finished create-virtualenv step (success) 12:18:32 INFO - [mozharness: 2017-09-28 19:18:32.163399Z] Running install step. 12:18:32 INFO - Running pre-action listener: _resource_record_pre_action 12:18:32 INFO - Running main action method: install 12:18:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:18:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:18:32 INFO - Reading from file tmpfile_stdout 12:18:32 INFO - Detecting whether we're running mozinstall >=1.0... 12:18:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:18:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:18:32 INFO - Reading from file tmpfile_stdout 12:18:32 INFO - Output received: 12:18:32 INFO - Usage: mozinstall [options] installer 12:18:32 INFO - Options: 12:18:32 INFO - -h, --help show this help message and exit 12:18:32 INFO - -d DEST, --destination=DEST 12:18:32 INFO - Directory to install application into. [default: 12:18:32 INFO - "/builds/slave/test"] 12:18:32 INFO - --app=APP Application being installed. [default: firefox] 12:18:32 INFO - mkdir: /builds/slave/test/build/application 12:18:32 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'] 12:18:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:18:47 INFO - Reading from file tmpfile_stdout 12:18:47 INFO - Output received: 12:18:47 INFO - /builds/slave/test/build/application/firefox/firefox 12:18:47 INFO - Running post-action listener: _resource_record_post_action 12:18:47 INFO - [mozharness: 2017-09-28 19:18:47.091750Z] Finished install step (success) 12:18:47 INFO - [mozharness: 2017-09-28 19:18:47.091895Z] Running setup-mitmproxy step. 12:18:47 INFO - Running pre-action listener: _resource_record_pre_action 12:18:47 INFO - Running main action method: setup_mitmproxy 12:18:47 INFO - Skipping: mitmproxy is not required 12:18:47 INFO - Running post-action listener: _resource_record_post_action 12:18:47 INFO - [mozharness: 2017-09-28 19:18:47.092424Z] Finished setup-mitmproxy step (success) 12:18:47 INFO - [mozharness: 2017-09-28 19:18:47.092526Z] Running run-tests step. 12:18:47 INFO - Running pre-action listener: _resource_record_pre_action 12:18:47 INFO - Running pre-action listener: _set_gcov_prefix 12:18:47 INFO - Running main action method: run_tests 12:18:47 WARNING - Try message not found. 12:18:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:18:47 INFO - Python 2.7.3 12:18:47 INFO - Return code: 0 12:18:47 INFO - grabbing minidump binary from tooltool 12:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:47 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 0x175f090>, '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 0x175fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1759f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:18:47 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 12:18:47 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 12:18:47 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 12:18:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:18:47 INFO - Return code: 0 12:18:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:18:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:18:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:18:47 INFO - ENV: RUST_BACKTRACE is now full 12:18:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:18:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:18:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 12:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 12:18:47 INFO - Using env: {'DISPLAY': ':0', 12:18:47 INFO - 'HOME': '/home/cltbld', 12:18:47 INFO - 'LANG': 'en_US.UTF-8', 12:18:47 INFO - 'LANGUAGE': 'en_US:en', 12:18:47 INFO - 'LOGNAME': 'cltbld', 12:18:47 INFO - 'MAIL': '/var/mail/cltbld', 12:18:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:18:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:18:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:47 INFO - 'MOZ_NO_REMOTE': '1', 12:18:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:18:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:18:47 INFO - 'NO_EM_RESTART': '1', 12:18:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:18:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:47 INFO - 'PWD': '/builds/slave/test', 12:18:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:18:47 INFO - 'RUST_BACKTRACE': 'full', 12:18:47 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:18:47 INFO - 'SHELL': '/bin/bash', 12:18:47 INFO - 'SHLVL': '1', 12:18:47 INFO - 'TERM': 'linux', 12:18:47 INFO - 'TMOUT': '86400', 12:18:47 INFO - 'USER': 'cltbld', 12:18:47 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', 12:18:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:47 INFO - '_': '/tools/buildbot/bin/python'} 12:18:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 12:18:47 INFO - mozversion application_buildid: 20170928180207 12:18:47 INFO - mozversion application_changeset: 1c3b6791f9433c7cf8d6ecb453c9277fd99a5b4a 12:18:47 INFO - mozversion application_display_name: Firefox 12:18:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:18:47 INFO - mozversion application_name: Firefox 12:18:47 INFO - mozversion application_remotingname: firefox 12:18:47 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:18:47 INFO - mozversion application_vendor: Mozilla 12:18:47 INFO - mozversion application_version: 57.0 12:18:47 INFO - mozversion platform_buildid: 20170928180207 12:18:47 INFO - mozversion platform_changeset: 1c3b6791f9433c7cf8d6ecb453c9277fd99a5b4a 12:18:47 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:18:47 INFO - mozversion platform_version: 57.0 12:18:47 INFO - using testdate: 1506626327 12:18:47 INFO - actual date: 1506626327 12:18:47 INFO - starting webserver on 'localhost:44693' 12:18:47 INFO - SUITE-START | Running 2 tests 12:18:47 INFO - TEST-START | dromaeo_css 12:18:47 INFO - Initialising browser for dromaeo_css test... 12:18:47 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44693/getInfo.html -profile /tmp/tmppCSkPZ/profile 12:18:47 INFO - TEST-INFO | started process 3595 (/builds/slave/test/build/application/firefox/firefox http://localhost:44693/getInfo.html) 12:18:55 INFO - TEST-INFO | 3595: exit 0 12:18:55 INFO - Browser initialized. 12:18:55 INFO - Running cycle 1/1 for dromaeo_css test... 12:18:55 INFO - Using env: {'DISPLAY': ':0', 12:18:55 INFO - 'HOME': '/home/cltbld', 12:18:55 INFO - 'JSGC_DISABLE_POISONING': '1', 12:18:55 INFO - 'LANG': 'en_US.UTF-8', 12:18:55 INFO - 'LANGUAGE': 'en_US:en', 12:18:55 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:18:55 INFO - 'LOGNAME': 'cltbld', 12:18:55 INFO - 'MAIL': '/var/mail/cltbld', 12:18:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:18:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:18:55 INFO - 'MOZ_CRASHREPORTER': '1', 12:18:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:55 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:18:55 INFO - 'MOZ_NO_REMOTE': '1', 12:18:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:18:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:18:55 INFO - 'NO_EM_RESTART': '1', 12:18:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:18:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:55 INFO - 'PWD': '/builds/slave/test', 12:18:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:18:55 INFO - 'RUST_BACKTRACE': 'full', 12:18:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:18:55 INFO - 'SHELL': '/bin/bash', 12:18:55 INFO - 'SHLVL': '1', 12:18:55 INFO - 'TERM': 'linux', 12:18:55 INFO - 'TMOUT': '86400', 12:18:55 INFO - 'USER': 'cltbld', 12:18:55 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', 12:18:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:55 INFO - '_': '/tools/buildbot/bin/python'} 12:18:55 INFO - TEST-INFO | started process 3781 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppCSkPZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:18:56 INFO - PID 3781 | 12:18:56 INFO - PID 3781 | (/builds/slave/test/build/application/firefox/firefox:3829): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:18:56 INFO - PID 3781 | 12:19:07 INFO - PID 3781 | RSS: Main: 142917632 12:19:07 INFO - PID 3781 | 12:22:04 INFO - PID 3781 | Cycle 1(1): loaded http://localhost:44693/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44693/tests/dromaeo/cssquery-ext.html) 12:22:05 INFO - PID 3781 | RSS: Main: 145932288 12:22:05 INFO - PID 3781 | 12:25:07 INFO - PID 3781 | Cycle 1(1): loaded http://localhost:44693/tests/dromaeo/cssquery-ext.html (next: http://localhost:44693/tests/dromaeo/cssquery-jquery.html) 12:25:07 INFO - PID 3781 | RSS: Main: 144535552 12:25:07 INFO - PID 3781 | 12:28:09 INFO - PID 3781 | Cycle 1(1): loaded http://localhost:44693/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44693/tests/dromaeo/cssquery-mootools.html) 12:28:10 INFO - PID 3781 | RSS: Main: 145334272 12:28:10 INFO - PID 3781 | 12:31:11 INFO - PID 3781 | Cycle 1(1): loaded http://localhost:44693/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44693/tests/dromaeo/cssquery-prototype.html) 12:31:12 INFO - PID 3781 | RSS: Main: 145625088 12:31:12 INFO - PID 3781 | 12:34:15 INFO - PID 3781 | Cycle 1(1): loaded http://localhost:44693/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44693/tests/dromaeo/cssquery-yui.html) 12:34:15 INFO - PID 3781 | RSS: Main: 146706432 12:34:15 INFO - PID 3781 | 12:37:17 INFO - PID 3781 | Cycle 1(1): loaded http://localhost:44693/tests/dromaeo/cssquery-yui.html (next: http://localhost:44693/tests/dromaeo/cssquery-dojo.html) 12:37:17 INFO - PID 3781 | RSS: Main: 149061632 12:37:17 INFO - PID 3781 | 12:37:17 INFO - PID 3781 | __start_tp_report 12:37:17 INFO - PID 3781 | _x_x_mozilla_page_load 12:37:17 INFO - PID 3781 | _x_x_mozilla_page_load_details 12:37:17 INFO - PID 3781 | |i|pagename|runs| 12:37:17 INFO - PID 3781 | |0;dojo.html;4043.2924238258297;4045.332520134178;4055.7160998730083;4071.7149799514027;4078.6533144682708;6570.055973386617;6642.734290628381;6709.932900670992;6727.899081513775;6730.125083739107;6654.767083152091;6660.367265109807;6663.866722665556;6709.865802683956;6762.188537375522;5936.940630593682;5962.34414214436;5977.342492325841;6003.4596886280115;6033.125196846455;5898.262717160355;5945.435183657545;6006.039033754579;6019.3980601939675;6083.939160608404;6022.397760223987;6042.093685947121;6056;6074.665893375863;6102.420270074358;5942.108683697436;5984.431479009487;6005.669688167149;6095.024796032636;6100.23747031621;5881.735321910482;5885.764569417218;5912.645241285493;5913.024350982126;5933.9406605934255;6549.312322206195;6643.93356066438;6647;6649.102371179877;6702.597844129319;7149.714011439536;7167.175774785862;7171.677274522659;7227.710891564331;7252.746153884642;7205.819854503627;7288.635568221568;7291.562506249589;7303.415726741848;7323.853522929565;6574.145361103026;6622.966885165543;6643.036759669821;6695.096162018114;6724.697388617475;7294.051773269547;7348.512468099354;7379.1513975892385;7395.8151046224275;7407.111146662327;7007.404370628554;7042.7887163385185;7096.716131354791;7131.429485641136;7133.5719856808755;7127.251638577978;7192.856142877218;7206.243344448862;7272.672729727175;7301;7069.505134640629;7075.646217689095;7090.07828982229;7107.36033756954;7123.82190445243;7186.856262874713;7209.351158395664;7232.855342893113;7266.564006159648;7279.745208917662;5308.416074231832;5317.867053323621;5318.627696061316;5386.030514507348;5394.406615272317;4774.427068751772;4805.37530121082;4818.927716084261;4835.854924352275;4890;6692.966535167293;6716.83207919806;6744.291849355747;6750.932490675129;6777.966110169417;7129.679164437613;7212.531185473033;7288.817779555554;7300.817479563053;7308.196098429168;6588.209414870246;6633.568818026813;6638.90041649366;6676.699548520328;6719.798406047827;6048.4858787003595;6105.786297479655;6110.297315808739;6126.050462178369;6135.84660383494;7537.321641052332;7550;7637.968874201912;7642.388608911274;7649.04386951631;7054.788356349318;7072.186698529735;7201.3158749918675;7306.6711997959155;7311.086114235721;7520.548767073993;7539.321461068311;7540;7541.7360392386;7542.849143016999;7080.681369338289;7104.04095447108;7105.2894710528535;7125.5368401053765;7204.639768011683;7185.604791736296;7199.388052015428;7277.708891644275;7283.308085731847;7285;6870.587764734132;6962.268961758942;6966.094407726963;6972.755953541602;6979.30206979298;6958.373746362851;6974.511784175059;6983.127109111361;6987.860242795217;6990.755323563651;6843.828904277433;6910.032595436543;6929.411000065051;6930.896036559355;6933.618650974043;6942.51402401827;7020.859582808213;7035.753748618774;7081.68132434052;7129.643517823984;6697.028930805103;6739.42714869222;6755.493337999571;6813.0461735358085;6851.246362900177 12:37:17 INFO - PID 3781 | |1;ext.html;992.7666998255409;1021.3106153346491;1022.560299071399;1023.595679706516;1033.9017793309633;47843.28235076477;48528;48593;48631.27053094202;48772.51227487724;44523.55476445243;44624;44669.10661786771;44701.776491117584;44804.327935081;8012.679492820294;8087.353011759074;8125.390595705317;8130.268275855172;8135.430519863612;7720.613969301512;7750.7674769756995;7781.182975787546;7782.961085194566;7785.260400261966;8468.407211495198;8471.449355791854;8480.703175388862;8484.151584841527;8554.743357699279;8420.621072051772;8472;8485.575721213945;8487.830243395098;8489.61796719149;8550.973883134002;8603.096674849114;8614.913850861474;8633.13668633138;8635.050144484101;43594.12811743779;43844.342334865;43849.56150438487;43890.341644875356;44006.339904901455;3949.3681011038384;3976.642102210786;3978.9602103978877;3980.4626375439234;3983.7609743415487;35729.82135089308;35852;35934;35936.10159746001;35953;5379.81170659029;5407.107827208506;5445.83662490126;5447.727613619343;5457.072297709399;228612;228652;229125;229231;229547;49363;50048.74975625172;56420.43579564234;57301.42698572961;57474.425255747745;59681.10478342828;59739.701301493216;59761.40238597558;59897.70051149716;59973.70013149906;17917.7312340315;17949.012804295628;17988.190531426117;17993.910030449762;18095.09524523795;14575.416983320549;14890.553283401516;14942.626434339229;14995.700085998436;14999.550013499613;15644.765328520081;15747.763783543256;15861.920690396475;15878.60303492422;16019.759703604455;6098.5426093042315;6335.081413195153;6383.585066970632;6394.360563943662;6420.775272865428;1107.7839821234782;1108.578740078765;1130.37829193943;1131.213806404541;1137.3460260350332;26889;26911.86544067267;26980.32549186286;27190.5921411179;27457.31356716098;1236.6042866282703;1237.2576454127452;1243.9564615238423;1246.8254444377794;1252.843394575678;13953.860461395256;14076.366563504462;14180.149191048364;14268.357923893449;14282.928585357215;307566;308572;309180;309529;311775;62125;62215.688921555105;62373.37626623766;62524;62773.686131569964;1574.9370025198864;1576.5428025872598;1592.7371983622804;1610.9113998729947;1618.9676206475804;26156.60765088487;26244.4751104973;26385;26577.33556661099;26666.600000999613;44029.77985110054;44307.556924431636;44398.55601443944;44409.55590444183;44459.33311000402;253494;254110;254344;256576;256625;35489.1127721809;37727;38994.80502597469;44399.55600443954;45082;56225.43774562202;56305.71847140902;56714.14928776154;56885;57103;39885.2022959545;40607.1878562433;40705;40745.79627101846;41239.793801030806;39436.40845387264;40177.39733904052;40283.1943361137;40462.393064103475;41059.17881642291;46004.539954600026;46679.53320466752;46883.76558117187;47144.29283560818;47160.52839471561;38287;38347.616523834404;38348.6165138345;38467.2306553873;38515.61484385232;27408.58887116655;28118.578221326286;28349;28510.857445712638;28932.710672893845 12:37:17 INFO - PID 3781 | |2;jquery.html;634.368889854443;634.8410003087374;638.9403509795627;648.4291852260632;652.5399593286734;22924.65613015804;23254.41863953402;23921.282361529138;24133.75866241337;24165.75834241657;25906.481870362582;26853.462930741374;26872.59691104635;26896.193114206555;26944.865275673594;6708.195016598015;6711;6860.142482189726;6869.519133660646;6946.930530694679;6907.34380233877;6910.619915904624;6914.723411063576;6957.6869040893025;6966.4078553323025;6903.965480172592;6911.274316196802;6913;6920.031195632616;6931.826704332381;6884.795160846832;6903.827404314882;6912.55068420551;6927.58434493932;6928.203256625477;6476.190476190476;6484.1894763154605;6489.994050922068;6495.4803615710625;6510.381513756186;29832.403351933055;31773.36453270945;31970.680293197;32011.519827202377;32020.359592808247;2299.2642354446584;2330.254318618043;2333.0317918063984;2337.5558643857785;2345.519168570439;20716.274930377516;20844;21297.574048519098;21475.67786483204;21509.56980860374;2539.987300063506;2567.5121726871835;2579.509893120301;2598.4413351129597;2612.568926127187;147294;152615;173526;175952;176648;23407.180748674054;23904;24326.270211893672;25505.744942550336;25603.61594576083;49763.75118124459;50049.74776135082;50106.49893501091;50374.244386334234;50530.98938021292;13024.348782560834;13194.472221111047;13351.399187036606;13436.328183590782;13465.326733663278;10756.515956781963;10834.241603087867;10837;10856.077233435246;10857.294275872044;12482.126251162514;12688.432164420801;12756.872431275755;12843.357832108357;12907.354632268349;3813.790241536742;4217.97891010543;4361.890952726208;4480.633406810889;4506.369108324837;1054.752133248686;1059.708580140452;1065.2702898514542;1071.0521188748014;1071.276888100529;20403.285884993955;21352.252671156435;21643.35069947904;21701.34895953124;21907.233246836284;1113.2318700096907;1126.7070561653866;1127.8421021057059;1128.114430172311;1139.5897476908306;11731.530738770354;11805.52777888892;11878.524859005716;11885.584004559973;11915.88084119165;579169;586068;590934;623033;625464;135924.32037839945;136783.31608342094;137080;137698.31150844382;138869;1255.7049093463029;1273.1597145883673;1298.4935875008755;1301.804729290614;1306.8693130686854;18848;19335.51661208481;19368.612627747083;19406.514837129183;19530.804691952897;66307.33692663012;66620.6668966652;66866.66566667135;67084.6645766768;67340.6632966832;83177.58411207906;84549;85077.14922850693;86212;86881;21452.141914323878;21554;21727.34817955464;21840.78159218451;21934.45163870916;24446.388840279134;24525.38686532851;24651.876740616182;24659.753402466464;25224.873875630503;19859.404217873485;20040.89979550093;20060.69908951338;20170.092345844765;20197.59604807925;17642.149243586482;17680.469585912444;17782;18425.907870460564;18515.166817493515;19004.144813483705;19090.33183838559;19098.56760742922;19125.713114303573;19518.707219391436;20692.068856901173;20800.68798968047;20866.37400877976;20927.37217883405;20978.37064888056;18266.634667306844;18418.35535756242;18801.717974230123;18979.810201897802;19148.425547233604 12:37:17 INFO - PID 3781 | |3;mootools.html;1458.60617633239;1500.609841441225;1512.1683074309128;1525.1840265457975;1538.323137819359;17372.391966281182;18054.097295135256;18139;18145.45563633093;18145.54636134094;17741.37905173319;17849.7322540162;17886.195121219545;17905.462836114904;17914.193861276242;3086.27472543952;3123.5158550424662;3173.1591128351006;3176.618805743314;3178.698023687746;3083.784135110543;3112.2219445138717;3113.377324535091;3119.282565010049;3134.796238244518;2887.1627228103825;2996.4905965985727;2997.355568552761;3052.13014290926;3070.5547695584128;3102.4260511805387;3135.4826453635123;3167.3506931079305;3167.5565420840985;3169.524571314298;2982.835944023078;2998.055612482061;2998.0705981145697;2999.2052106191877;3020.275133967832;17114.315427383015;17245.137743112795;17253.827461725472;17338.56653583658;17364.65270694579;8554.572271386405;8770.386072974894;8782.692605758768;8839.734807955772;8866.379353445262;5453.918191227135;5519.2824932758485;5602.831915042555;5743.167240750068;5773.9422605773825;2083.624947509448;2086.3010891351414;2112.1340250497374;2118.7457505099333;2159.276642324823;32108;32551.51172732411;32659.510107348407;32705.67294327074;32711.6728832711;7710.884336734954;7719.112302085332;7727.265909738566;7921.564313962672;7940.205979402013;20385.184592616424;20465.897670511553;20545.58908821845;20552.588948220953;20562.897185513975;8259;8394.160583941679;8604.48373097616;8802.07578204292;8894.4663320201;7691.30778229962;7876.448648594658;7985.081715602664;8082.515049096955;8091.0695270043525;5911.142884281841;6046.425589568984;6050.727717252734;6096.573239873166;6105.45050945417;1743.7645917801187;1747.1700942052423;1758.5164079878136;1763.078244713243;1798.2896755781655;826.9007719073629;1010.722051435861;1028.861103750999;1030.7886883188928;1044.5456226541328;9749.71143612366;9770.31607787448;9909.157721593601;9931.354461959949;10422;810.1372038778682;829.4442723375353;849.5285116760257;861.9353548483889;927.7216834949623;6357;6632.336766323329;6635.568688035262;6687.164104486939;6688.632125233159;25385.492290154463;27311.590326144727;27525;27527;27553.31116722098;26262.343441413734;26727.732722672525;26743.331416714736;27341.863290683817;27375.17874463769;12545.93727031359;12548.435320410603;12552.497900083894;12595.93702031484;12654.114212005255;9021.729348119567;9250.121238482334;9363.531823408939;9414.717558473258;9472.810543788948;21977.90110494437;22524.09903603903;22840.771592283865;22961.770382295963;23193.072277108728;23417.41456463534;23501;23751.881240593688;23790.40523986845;23822.16622418207;12151.574694885636;12155.392230388268;12347.75304493923;12381.876181238073;12418.689532761753;14975;15049.774253386428;15155.090694558361;15155.242237887842;15175.392984280505;12949.482020719443;13283.136596121607;13303.135296205715;13487.25820079905;13527.256000920044;12658;12788.808167877303;12835.42240599194;13124.343782811013;13152;11623.76752464963;11720.472578734149;11769.588064417705;11798.469068892093;11858.703532411759;8140.796480088045;8149.429539932385;8265;8294.958525207336;8317.875231871405;8420.536870471939;8442.366822488215;8476.618552165291;8515.361347899056;8586.570671466527 12:37:17 INFO - PID 3781 | |4;prototype.html;574.0556784090172;575.4561938967677;582.2663444060482;599.7810799058344;600.2287061126453;6241.719122639481;6441.516886233528;6462.450691691212;6471.4175724184815;6508.54440189187;6284.497240220783;6329.398707122816;6345.143405640249;6361.9363806362035;6417.935820641792;5224.63427560071;5250.291210686566;5294.444083371249;5304.20436934459;5306.7346632668405;5149.391294791179;5180.922286165711;5199.324087868572;5271.762770675329;5331.493508116722;5320.414754377015;5351.866203344908;5361.51746342829;5362.51737343639;5387.434319396466;5281;5349.438308977559;5349.973250133725;5350.652207606532;5369.865753356158;5013.799448022075;5019.121653710623;5040.672356296829;5041.546260836542;5094.668846525001;6343.238811342622;6398.7760428385245;6403.111082230706;6419.903701444436;6423.164988551505;6262.718177682015;6336.778212762577;6383.329750376237;6388.137601423795;6400.647964361959;6824.7952561423235;6838;6912.412444942186;6920.826979325507;6933.394634789932;5381.596380271495;5560.833175004756;5601.60788744788;5632.35227948787;5635.09838425854;6989.7553585624255;7118;7138.250483699188;7155.821104472378;7184.389326907271;5851.444112809361;5855.3851845556455;5862.179294898718;5874.765009399576;5879.118132280192;5929.940700593025;5936.5547583931375;5944.613600115978;5957.851053723692;6012.428819262163;6228.252609686867;6337.302896681362;6354.110424540568;6368.745250189941;6369.840753981095;6862.9313706863295;6865.759698410531;6914.896276555855;6917.723291068301;6927.930720692729;2025.5543780368296;2047.3653167518116;2047.805458481442;2059.9588008240053;2070.1201989154547;1515.143943671822;1549.3957356630922;1557.4393218441353;1564.4837203722743;1574.850389213023;6091.634501929899;6110.755569777248;6198.6280823149855;6236.563440559208;6300.873982520324;6795.2185498667295;6823.181218253742;6824.829379265459;6920.273371296042;6922.826929326807;6244.313125556185;6261.342559031327;6281.277653069864;6310.403070249913;6342.207224096988;3616.258666973264;3632.473291372736;3641.9817900910325;3650.251698401821;3665.3872296189593;7100.573965562083;7156.284371562802;7207.675654595556;7217.422606191492;7247.601381924045;7010.649467526603;7023.578585284899;7057.8707406814665;7078.256783037707;7085.256048114977;7162.630795215019;7198.280171982759;7215.927840721631;7229.987801707765;7297.233790451926;6877.449804015767;6882.174139103339;6905.274946130584;6921.757738479129;6948.9305106950305;6718.798436046926;6785.760698969229;6810.897836532356;6818.590884546943;6862.622555759481;7199.400299850075;7212.764043146642;7227.421806255592;7230.710771569078;7265.709371625076;5797;5815;5815.76093204111;5848.561357898089;5854.385289544536;6726.831829204309;6915.135608048994;6982.217181230873;6984.825379365353;7007.8086725257845;6648.235452922976;6726.260111387841;6758.121444212417;6758.357955994173;6762.6618669065365;6621.205455345389;6636.279125558498;6648.800535983928;6690.665466726547;6700.597964122168;6489.675516224264;6546.312637173029;6718.563293385914;6723.831904202239;6731.730730770714;6643.30245324234;6706.463482921452;6730.562513436611;6749.93250067493;6851.3491218334175;6532.510061745293;6535.3137920517665;6541.280459149585;6558.409743123142;6593.637349945607 12:37:17 INFO - PID 3781 | |5;yui.html;1010.1666125446513;1015.6699072801339;1023.3808545829772;1035.6271742172821;1039.340019087879;19342.12960416781;19655.901720491394;19697.70453443196;19701.901490492546;19711.112999915003;18529.536761580934;18610.53473663156;18713.625727485443;18728.812711872877;18832.152553135107;1063.6383629565946;1073.6295977887655;1076.7092884921067;1078.250615822004;1084.3331351219022;1073.404260635347;1076.580133747839;1079.7084787107476;1081.3349789879246;1082.2965072702727;1091.1870656361023;1133.0822034152322;1140.344302026334;1142.7371704507968;1143.3254379915847;1134.9886501135006;1138.4706111658118;1138.698244965085;1138.9886101138966;1139.3448766959032;832.950022998622;834.1450013735908;834.3450391442689;837.9581020948929;838.4256784102909;18832.246710131578;19031.524211894673;19075;19083.522911927314;19109.904450477658;20542;21036.89481552598;21120.2607908724;21231;21248.681269780966;16977.151142442952;17079;17261.913690431593;17404.738928916075;17493.212805423787;3933.3903244997073;3956.7032472564674;3978.3833505806647;3979.383195604686;3980.2636512245326;148590.25704871514;158270;159065.8186836257;159332;160749.19625401916;16098.67802643964;16278.674426511403;16406;16527.421540246032;16559.6688066238;30063.248418789266;30520.38959220803;30614.54078188829;30631.69368306333;31059.378812423623;8954.328425368123;8955.2835773138;8958.059403762642;8975.461472311683;8977.192052715287;4650.883727906829;4728.621710263171;4742.122707299091;4743.928841067387;4756.762161891892;5527.170924361298;5811.709414529257;5894.911576326359;5897.587168898136;5915.940840591625;1168.415792103948;1207.034372501995;1218.8598311194326;1222.4743360355133;1225.2893321873291;749.4566439331442;769.5459678789541;774.0556521044317;777.8405426887474;791.6595863778515;12816.935915320364;13368.99732520065;13845.653858653413;14223.573292801233;14377.496787612383;771.5139462138817;772.6600295869912;773.0954782904016;776.2664282253216;788.9289963903276;6216.347283535254;6261.624302541862;6299.6535190564955;6388.616682999035;6395.6482393468805;154841;156435.21782391015;156823;157698;161901;128546;129547;132333;132501;135851;119936;120370;120639.39680301542;120683;122245.38877305557;11821;11995.940020300191;12011.339376334385;12191.939040304742;12399.318037508023;58746.4125358758;59107.70446147742;59793.80412391641;60568.39431605628;60838;73886;74342;74342;76614;78285;17467.12664366751;17603.735943960593;17685.38101166453;17951.281948721906;17972.730409044136;18151.364702235358;18483.353082642043;18487.35294264748;18581.90709046446;18693.719594205824;18284.268629254682;18373.908130459262;18376.173072211513;18482.81517184811;18495.53761155928;16553.503394898173;16804.831951680328;16899.408520701716;16987.235574398936;17000.48998530046;16340.346386144896;16439.753403698716;16613.50159495217;16725.749113763548;16796.832031679525;13672;13767.655808604864;13877.86122138806;13900.443982240597;13924.094933829268;13412.262325572186;13546;13663.590092297247;13780.310984450938;13785.655358616115 12:37:17 INFO - PID 3781 | __end_tp_report 12:37:17 INFO - PID 3781 | __start_cc_report 12:37:17 INFO - PID 3781 | _x_x_mozilla_cycle_collect,260 12:37:17 INFO - PID 3781 | __end_cc_report 12:37:17 INFO - PID 3781 | __startTimestamp1506627437345__endTimestamp 12:37:17 INFO - PID 3781 | 12:37:17 INFO - PID 3781 | ------- Summary: start ------- 12:37:17 INFO - PID 3781 | Number of tests: 6 12:37:17 INFO - PID 3781 | 12:37:17 INFO - PID 3781 | [#0] dojo.html Cycles:165 Average:6648.93 Median:6910.03 stddev:779.23 (11.3%) stddev-sans-first:754.34 12:37:17 INFO - PID 3781 | Values: 4043.3 4045.3 4055.7 4071.7 4078.7 6570.1 6642.7 6709.9 6727.9 6730.1 6654.8 6660.4 6663.9 6709.9 6762.2 5936.9 5962.3 5977.3 6003.5 6033.1 5898.3 5945.4 6006.0 6019.4 6083.9 6022.4 6042.1 6056.0 6074.7 6102.4 5942.1 5984.4 6005.7 6095.0 6100.2 5881.7 5885.8 5912.6 5913.0 5933.9 6549.3 6643.9 6647.0 6649.1 6702.6 7149.7 7167.2 7171.7 7227.7 7252.7 7205.8 7288.6 7291.6 7303.4 7323.9 6574.1 6623.0 6643.0 6695.1 6724.7 7294.1 7348.5 7379.2 7395.8 7407.1 7007.4 7042.8 7096.7 7131.4 7133.6 7127.3 7192.9 7206.2 7272.7 7301.0 7069.5 7075.6 7090.1 7107.4 7123.8 7186.9 7209.4 7232.9 7266.6 7279.7 5308.4 5317.9 5318.6 5386.0 5394.4 4774.4 4805.4 4818.9 4835.9 4890.0 6693.0 6716.8 6744.3 6750.9 6778.0 7129.7 7212.5 7288.8 7300.8 7308.2 6588.2 6633.6 6638.9 6676.7 6719.8 6048.5 6105.8 6110.3 6126.1 6135.8 7537.3 7550.0 7638.0 7642.4 7649.0 7054.8 7072.2 7201.3 7306.7 7311.1 7520.5 7539.3 7540.0 7541.7 7542.8 7080.7 7104.0 7105.3 7125.5 7204.6 7185.6 7199.4 7277.7 7283.3 7285.0 6870.6 6962.3 6966.1 6972.8 6979.3 6958.4 6974.5 6983.1 6987.9 6990.8 6843.8 6910.0 6929.4 6930.9 6933.6 6942.5 7020.9 7035.8 7081.7 7129.6 6697.0 6739.4 6755.5 6813.0 6851.2 12:37:17 INFO - PID 3781 | 12:37:17 INFO - PID 3781 | [#1] ext.html Cycles:180 Average:46020.05 Median:40372.79 stddev:69378.75 (171.8%) stddev-sans-first:69491.00 12:37:17 INFO - PID 3781 | Values: 992.8 1021.3 1022.6 1023.6 1033.9 47843.3 48528.0 48593.0 48631.3 48772.5 44523.6 44624.0 44669.1 44701.8 44804.3 8012.7 8087.4 8125.4 8130.3 8135.4 7720.6 7750.8 7781.2 7783.0 7785.3 8468.4 8471.4 8480.7 8484.2 8554.7 8420.6 8472.0 8485.6 8487.8 8489.6 8551.0 8603.1 8614.9 8633.1 8635.1 43594.1 43844.3 43849.6 43890.3 44006.3 3949.4 3976.6 3979.0 3980.5 3983.8 35729.8 35852.0 35934.0 35936.1 35953.0 5379.8 5407.1 5445.8 5447.7 5457.1 228612.0 228652.0 229125.0 229231.0 229547.0 49363.0 50048.7 56420.4 57301.4 57474.4 59681.1 59739.7 59761.4 59897.7 59973.7 17917.7 17949.0 17988.2 17993.9 18095.1 14575.4 14890.6 14942.6 14995.7 14999.6 15644.8 15747.8 15861.9 15878.6 16019.8 6098.5 6335.1 6383.6 6394.4 6420.8 1107.8 1108.6 1130.4 1131.2 1137.3 26889.0 26911.9 26980.3 27190.6 27457.3 1236.6 1237.3 1244.0 1246.8 1252.8 13953.9 14076.4 14180.1 14268.4 14282.9 307566.0 308572.0 309180.0 309529.0 311775.0 62125.0 62215.7 62373.4 62524.0 62773.7 1574.9 1576.5 1592.7 1610.9 1619.0 26156.6 26244.5 26385.0 26577.3 26666.6 44029.8 44307.6 44398.6 44409.6 44459.3 253494.0 254110.0 254344.0 256576.0 256625.0 35489.1 37727.0 38994.8 44399.6 45082.0 56225.4 56305.7 56714.1 56885.0 57103.0 39885.2 40607.2 40705.0 40745.8 41239.8 39436.4 40177.4 40283.2 40462.4 41059.2 46004.5 46679.5 46883.8 47144.3 47160.5 38287.0 38347.6 38348.6 38467.2 38515.6 27408.6 28118.6 28349.0 28510.9 28932.7 12:37:17 INFO - PID 3781 | 12:37:17 INFO - PID 3781 | [#2] jquery.html Cycles:180 Average:42033.66 Median:12611.96 stddev:101167.28 (802.2%) stddev-sans-first:101403.33 12:37:17 INFO - PID 3781 | Values: 634.4 634.8 638.9 648.4 652.5 22924.7 23254.4 23921.3 24133.8 24165.8 25906.5 26853.5 26872.6 26896.2 26944.9 6708.2 6711.0 6860.1 6869.5 6946.9 6907.3 6910.6 6914.7 6957.7 6966.4 6904.0 6911.3 6913.0 6920.0 6931.8 6884.8 6903.8 6912.6 6927.6 6928.2 6476.2 6484.2 6490.0 6495.5 6510.4 29832.4 31773.4 31970.7 32011.5 32020.4 2299.3 2330.3 2333.0 2337.6 2345.5 20716.3 20844.0 21297.6 21475.7 21509.6 2540.0 2567.5 2579.5 2598.4 2612.6 147294.0 152615.0 173526.0 175952.0 176648.0 23407.2 23904.0 24326.3 25505.7 25603.6 49763.8 50049.7 50106.5 50374.2 50531.0 13024.3 13194.5 13351.4 13436.3 13465.3 10756.5 10834.2 10837.0 10856.1 10857.3 12482.1 12688.4 12756.9 12843.4 12907.4 3813.8 4218.0 4361.9 4480.6 4506.4 1054.8 1059.7 1065.3 1071.1 1071.3 20403.3 21352.3 21643.4 21701.3 21907.2 1113.2 1126.7 1127.8 1128.1 1139.6 11731.5 11805.5 11878.5 11885.6 11915.9 579169.0 586068.0 590934.0 623033.0 625464.0 135924.3 136783.3 137080.0 137698.3 138869.0 1255.7 1273.2 1298.5 1301.8 1306.9 18848.0 19335.5 19368.6 19406.5 19530.8 66307.3 66620.7 66866.7 67084.7 67340.7 83177.6 84549.0 85077.1 86212.0 86881.0 21452.1 21554.0 21727.3 21840.8 21934.5 24446.4 24525.4 24651.9 24659.8 25224.9 19859.4 20040.9 20060.7 20170.1 20197.6 17642.1 17680.5 17782.0 18425.9 18515.2 19004.1 19090.3 19098.6 19125.7 19518.7 20692.1 20800.7 20866.4 20927.4 20978.4 18266.6 18418.4 18801.7 18979.8 19148.4 12:37:17 INFO - PID 3781 | 12:37:17 INFO - PID 3781 | [#3] mootools.html Cycles:180 Average:10955.00 Median:2935.00 stddev:8224.03 (280.2%) stddev-sans-first:8216.15 12:37:17 INFO - PID 3781 | Values: 1458.6 1500.6 1512.2 1525.2 1538.3 17372.4 18054.1 18139.0 18145.5 18145.5 17741.4 17849.7 17886.2 17905.5 17914.2 3086.3 3123.5 3173.2 3176.6 3178.7 3083.8 3112.2 3113.4 3119.3 3134.8 2887.2 2996.5 2997.4 3052.1 3070.6 3102.4 3135.5 3167.4 3167.6 3169.5 2982.8 2998.1 2998.1 2999.2 3020.3 17114.3 17245.1 17253.8 17338.6 17364.7 8554.6 8770.4 8782.7 8839.7 8866.4 5453.9 5519.3 5602.8 5743.2 5773.9 2083.6 2086.3 2112.1 2118.7 2159.3 32108.0 32551.5 32659.5 32705.7 32711.7 7710.9 7719.1 7727.3 7921.6 7940.2 20385.2 20465.9 20545.6 20552.6 20562.9 8259.0 8394.2 8604.5 8802.1 8894.5 7691.3 7876.4 7985.1 8082.5 8091.1 5911.1 6046.4 6050.7 6096.6 6105.5 1743.8 1747.2 1758.5 1763.1 1798.3 826.9 1010.7 1028.9 1030.8 1044.5 9749.7 9770.3 9909.2 9931.4 10422.0 810.1 829.4 849.5 861.9 927.7 6357.0 6632.3 6635.6 6687.2 6688.6 25385.5 27311.6 27525.0 27527.0 27553.3 26262.3 26727.7 26743.3 27341.9 27375.2 12545.9 12548.4 12552.5 12595.9 12654.1 9021.7 9250.1 9363.5 9414.7 9472.8 21977.9 22524.1 22840.8 22961.8 23193.1 23417.4 23501.0 23751.9 23790.4 23822.2 12151.6 12155.4 12347.8 12381.9 12418.7 14975.0 15049.8 15155.1 15155.2 15175.4 12949.5 13283.1 13303.1 13487.3 13527.3 12658.0 12788.8 12835.4 13124.3 13152.0 11623.8 11720.5 11769.6 11798.5 11858.7 8140.8 8149.4 8265.0 8295.0 8317.9 8420.5 8442.4 8476.6 8515.4 8586.6 12:37:17 INFO - PID 3781 | 12:37:17 INFO - PID 3781 | [#4] prototype.html Cycles:180 Average:5889.08 Median:6399.71 stddev:1563.13 (24.4%) stddev-sans-first:1515.75 12:37:17 INFO - PID 3781 | Values: 574.1 575.5 582.3 599.8 600.2 6241.7 6441.5 6462.5 6471.4 6508.5 6284.5 6329.4 6345.1 6361.9 6417.9 5224.6 5250.3 5294.4 5304.2 5306.7 5149.4 5180.9 5199.3 5271.8 5331.5 5320.4 5351.9 5361.5 5362.5 5387.4 5281.0 5349.4 5350.0 5350.7 5369.9 5013.8 5019.1 5040.7 5041.5 5094.7 6343.2 6398.8 6403.1 6419.9 6423.2 6262.7 6336.8 6383.3 6388.1 6400.6 6824.8 6838.0 6912.4 6920.8 6933.4 5381.6 5560.8 5601.6 5632.4 5635.1 6989.8 7118.0 7138.3 7155.8 7184.4 5851.4 5855.4 5862.2 5874.8 5879.1 5929.9 5936.6 5944.6 5957.9 6012.4 6228.3 6337.3 6354.1 6368.7 6369.8 6862.9 6865.8 6914.9 6917.7 6927.9 2025.6 2047.4 2047.8 2060.0 2070.1 1515.1 1549.4 1557.4 1564.5 1574.9 6091.6 6110.8 6198.6 6236.6 6300.9 6795.2 6823.2 6824.8 6920.3 6922.8 6244.3 6261.3 6281.3 6310.4 6342.2 3616.3 3632.5 3642.0 3650.3 3665.4 7100.6 7156.3 7207.7 7217.4 7247.6 7010.6 7023.6 7057.9 7078.3 7085.3 7162.6 7198.3 7215.9 7230.0 7297.2 6877.4 6882.2 6905.3 6921.8 6948.9 6718.8 6785.8 6810.9 6818.6 6862.6 7199.4 7212.8 7227.4 7230.7 7265.7 5797.0 5815.0 5815.8 5848.6 5854.4 6726.8 6915.1 6982.2 6984.8 7007.8 6648.2 6726.3 6758.1 6758.4 6762.7 6621.2 6636.3 6648.8 6690.7 6700.6 6489.7 6546.3 6718.6 6723.8 6731.7 6643.3 6706.5 6730.6 6749.9 6851.3 6532.5 6535.3 6541.3 6558.4 6593.6 12:37:17 INFO - PID 3781 | 12:37:17 INFO - PID 3781 | [#5] yui.html Cycles:180 Average:28480.03 Median:17548.47 stddev:43291.79 (246.7%) stddev-sans-first:43364.10 12:37:17 INFO - PID 3781 | Values: 1010.2 1015.7 1023.4 1035.6 1039.3 19342.1 19655.9 19697.7 19701.9 19711.1 18529.5 18610.5 18713.6 18728.8 18832.2 1063.6 1073.6 1076.7 1078.3 1084.3 1073.4 1076.6 1079.7 1081.3 1082.3 1091.2 1133.1 1140.3 1142.7 1143.3 1135.0 1138.5 1138.7 1139.0 1139.3 833.0 834.1 834.3 838.0 838.4 18832.2 19031.5 19075.0 19083.5 19109.9 20542.0 21036.9 21120.3 21231.0 21248.7 16977.2 17079.0 17261.9 17404.7 17493.2 3933.4 3956.7 3978.4 3979.4 3980.3 148590.3 158270.0 159065.8 159332.0 160749.2 16098.7 16278.7 16406.0 16527.4 16559.7 30063.2 30520.4 30614.5 30631.7 31059.4 8954.3 8955.3 8958.1 8975.5 8977.2 4650.9 4728.6 4742.1 4743.9 4756.8 5527.2 5811.7 5894.9 5897.6 5915.9 1168.4 1207.0 1218.9 1222.5 1225.3 749.5 769.5 774.1 777.8 791.7 12816.9 13369.0 13845.7 14223.6 14377.5 771.5 772.7 773.1 776.3 788.9 6216.3 6261.6 6299.7 6388.6 6395.6 154841.0 156435.2 156823.0 157698.0 161901.0 128546.0 129547.0 132333.0 132501.0 135851.0 119936.0 120370.0 120639.4 120683.0 122245.4 11821.0 11995.9 12011.3 12191.9 12399.3 58746.4 59107.7 59793.8 60568.4 60838.0 73886.0 74342.0 74342.0 76614.0 78285.0 17467.1 17603.7 17685.4 17951.3 17972.7 18151.4 18483.4 18487.4 18581.9 18693.7 18284.3 18373.9 18376.2 18482.8 18495.5 16553.5 16804.8 16899.4 16987.2 17000.5 16340.3 16439.8 16613.5 16725.7 16796.8 13672.0 13767.7 13877.9 13900.4 13924.1 13412.3 13546.0 13663.6 13780.3 13785.7 12:37:17 INFO - PID 3781 | -------- Summary: end -------- 12:37:17 INFO - PID 3781 | 12:37:18 INFO - TEST-INFO | 3781: exit 0 12:37:18 INFO - TEST-OK | dromaeo_css | took 1110582ms 12:37:18 INFO - TEST-START | kraken 12:37:18 INFO - Initialising browser for kraken test... 12:37:18 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44693/getInfo.html -profile /tmp/tmpHJYBnq/profile 12:37:18 INFO - TEST-INFO | started process 4168 (/builds/slave/test/build/application/firefox/firefox http://localhost:44693/getInfo.html) 12:37:23 INFO - TEST-INFO | 4168: exit 0 12:37:23 INFO - Browser initialized. 12:37:23 INFO - Running cycle 1/1 for kraken test... 12:37:23 INFO - Using env: {'DISPLAY': ':0', 12:37:23 INFO - 'HOME': '/home/cltbld', 12:37:23 INFO - 'JSGC_DISABLE_POISONING': '1', 12:37:23 INFO - 'LANG': 'en_US.UTF-8', 12:37:23 INFO - 'LANGUAGE': 'en_US:en', 12:37:23 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:37:23 INFO - 'LOGNAME': 'cltbld', 12:37:23 INFO - 'MAIL': '/var/mail/cltbld', 12:37:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:37:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:37:23 INFO - 'MOZ_CRASHREPORTER': '1', 12:37:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:23 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:37:23 INFO - 'MOZ_NO_REMOTE': '1', 12:37:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:37:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:37:23 INFO - 'NO_EM_RESTART': '1', 12:37:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:37:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:23 INFO - 'PWD': '/builds/slave/test', 12:37:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:37:23 INFO - 'RUST_BACKTRACE': 'full', 12:37:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:37:23 INFO - 'SHELL': '/bin/bash', 12:37:23 INFO - 'SHLVL': '1', 12:37:23 INFO - 'TERM': 'linux', 12:37:23 INFO - 'TMOUT': '86400', 12:37:23 INFO - 'USER': 'cltbld', 12:37:23 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820', 12:37:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:23 INFO - '_': '/tools/buildbot/bin/python'} 12:37:23 INFO - TEST-INFO | started process 4358 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHJYBnq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:37:24 INFO - PID 4358 | 12:37:24 INFO - PID 4358 | (/builds/slave/test/build/application/firefox/firefox:4406): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:37:24 INFO - PID 4358 | 12:37:34 INFO - PID 4358 | RSS: Main: 141451264 12:37:34 INFO - PID 4358 | 12:37:37 INFO - PID 4358 | Cycle 1(1): loaded http://localhost:44693/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44693/tests/kraken/driver.html?testName=audio-beat-detection) 12:37:38 INFO - PID 4358 | RSS: Main: 147550208 12:37:38 INFO - PID 4358 | 12:37:40 INFO - PID 4358 | Cycle 1(1): loaded http://localhost:44693/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44693/tests/kraken/driver.html?testName=audio-dft) 12:37:41 INFO - PID 4358 | RSS: Main: 148062208 12:37:41 INFO - PID 4358 | 12:37:44 INFO - PID 4358 | Cycle 1(1): loaded http://localhost:44693/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44693/tests/kraken/driver.html?testName=audio-fft) 12:37:44 INFO - PID 4358 | RSS: Main: 149491712 12:37:44 INFO - PID 4358 | 12:37:46 INFO - PID 4358 | Cycle 1(1): loaded http://localhost:44693/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44693/tests/kraken/driver.html?testName=audio-oscillator) 12:37:47 INFO - PID 4358 | RSS: Main: 149008384 12:37:47 INFO - PID 4358 | 12:37:49 INFO - PID 4358 | Cycle 1(1): loaded http://localhost:44693/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44693/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:37:50 INFO - PID 4358 | RSS: Main: 149331968 12:37:50 INFO - PID 4358 | 12:37:53 INFO - PID 4358 | Cycle 1(1): loaded http://localhost:44693/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44693/tests/kraken/driver.html?testName=imaging-darkroom) 12:37:53 INFO - PID 4358 | RSS: Main: 148877312 12:37:53 INFO - PID 4358 | 12:37:57 INFO - PID 4358 | Cycle 1(1): loaded http://localhost:44693/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44693/tests/kraken/driver.html?testName=imaging-desaturate) 12:37:57 INFO - PID 4358 | RSS: Main: 150478848 12:37:57 INFO - PID 4358 | 12:38:00 INFO - PID 4358 | Cycle 1(1): loaded http://localhost:44693/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44693/tests/kraken/driver.html?testName=json-parse-financial) 12:38:01 INFO - PID 4358 | RSS: Main: 151793664 12:38:01 INFO - PID 4358 | 12:38:02 INFO - PID 4358 | Cycle 1(1): loaded http://localhost:44693/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44693/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:38:03 INFO - PID 4358 | RSS: Main: 151961600 12:38:03 INFO - PID 4358 | 12:38:05 INFO - PID 4358 | Cycle 1(1): loaded http://localhost:44693/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44693/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:38:05 INFO - PID 4358 | RSS: Main: 151691264 12:38:05 INFO - PID 4358 | 12:38:07 INFO - PID 4358 | Cycle 1(1): loaded http://localhost:44693/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44693/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:38:08 INFO - PID 4358 | RSS: Main: 148918272 12:38:08 INFO - PID 4358 | 12:38:10 INFO - PID 4358 | Cycle 1(1): loaded http://localhost:44693/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44693/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:38:11 INFO - PID 4358 | RSS: Main: 149078016 12:38:11 INFO - PID 4358 | 12:38:14 INFO - PID 4358 | Cycle 1(1): loaded http://localhost:44693/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44693/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:38:14 INFO - PID 4358 | RSS: Main: 149131264 12:38:14 INFO - PID 4358 | 12:38:16 INFO - PID 4358 | Cycle 1(1): loaded http://localhost:44693/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44693/tests/kraken/driver.html?testName=ai-astar) 12:38:16 INFO - PID 4358 | RSS: Main: 150700032 12:38:16 INFO - PID 4358 | 12:38:16 INFO - PID 4358 | __start_tp_report 12:38:16 INFO - PID 4358 | _x_x_mozilla_page_load 12:38:16 INFO - PID 4358 | _x_x_mozilla_page_load_details 12:38:16 INFO - PID 4358 | |i|pagename|runs| 12:38:16 INFO - PID 4358 | |0;ai-astar;104;105;106;105;104;107;106;106;105;105 12:38:16 INFO - PID 4358 | |1;audio-beat-detection;117;111;110;112;120;113;111;112;142;112 12:38:16 INFO - PID 4358 | |2;audio-dft;162;164;160;162;160;161;161;163;162;165 12:38:16 INFO - PID 4358 | |3;audio-fft;72;72;73;72;72;74;73;78;73;73 12:38:16 INFO - PID 4358 | |4;audio-oscillator;77;78;77;77;76;82;77;76;76;77 12:38:16 INFO - PID 4358 | |5;imaging-gaussian-blur;114;114;110;113;111;114;114;111;113;110 12:38:16 INFO - PID 4358 | |6;imaging-darkroom;150;150;148;149;149;148;149;149;149;148 12:38:16 INFO - PID 4358 | |7;imaging-desaturate;93;96;99;95;94;94;95;94;94;95 12:38:16 INFO - PID 4358 | |8;json-parse-financial;45;45;45;44;44;43;45;43;45;46 12:38:16 INFO - PID 4358 | |9;json-stringify-tinderbox;42;40;39;39;39;39;40;39;39;38 12:38:16 INFO - PID 4358 | |10;stanford-crypto-aes;69;69;70;70;69;72;74;69;70;73 12:38:16 INFO - PID 4358 | |11;stanford-crypto-ccm;105;101;108;108;102;102;112;108;103;104 12:38:16 INFO - PID 4358 | |12;stanford-crypto-pbkdf2;158;154;148;147;150;152;155;147;155;149 12:38:16 INFO - PID 4358 | |13;stanford-crypto-sha256-iterative;61;61;60;59;56;72;58;57;59;61 12:38:16 INFO - PID 4358 | __end_tp_report 12:38:16 INFO - PID 4358 | __start_cc_report 12:38:16 INFO - PID 4358 | _x_x_mozilla_cycle_collect,659 12:38:16 INFO - PID 4358 | __end_cc_report 12:38:16 INFO - PID 4358 | __startTimestamp1506627496520__endTimestamp 12:38:16 INFO - PID 4358 | 12:38:16 INFO - PID 4358 | ------- Summary: start ------- 12:38:16 INFO - PID 4358 | Number of tests: 14 12:38:16 INFO - PID 4358 | 12:38:16 INFO - PID 4358 | [#0] ai-astar Cycles:10 Average:105.30 Median:105.50 stddev:0.95 (0.9%) stddev-sans-first:0.88 12:38:16 INFO - PID 4358 | Values: 104.0 105.0 106.0 105.0 104.0 107.0 106.0 106.0 105.0 105.0 12:38:16 INFO - PID 4358 | 12:38:16 INFO - PID 4358 | [#1] audio-beat-detection Cycles:10 Average:116.00 Median:112.50 stddev:9.64 (8.6%) stddev-sans-first:10.22 12:38:16 INFO - PID 4358 | Values: 117.0 111.0 110.0 112.0 120.0 113.0 111.0 112.0 142.0 112.0 12:38:16 INFO - PID 4358 | 12:38:16 INFO - PID 4358 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:1.63 (1.0%) stddev-sans-first:1.73 12:38:16 INFO - PID 4358 | Values: 162.0 164.0 160.0 162.0 160.0 161.0 161.0 163.0 162.0 165.0 12:38:16 INFO - PID 4358 | 12:38:16 INFO - PID 4358 | [#3] audio-fft Cycles:10 Average:73.20 Median:73.00 stddev:1.81 (2.5%) stddev-sans-first:1.87 12:38:16 INFO - PID 4358 | Values: 72.0 72.0 73.0 72.0 72.0 74.0 73.0 78.0 73.0 73.0 12:38:16 INFO - PID 4358 | 12:38:16 INFO - PID 4358 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:77.00 stddev:1.77 (2.3%) stddev-sans-first:1.87 12:38:16 INFO - PID 4358 | Values: 77.0 78.0 77.0 77.0 76.0 82.0 77.0 76.0 76.0 77.0 12:38:16 INFO - PID 4358 | 12:38:16 INFO - PID 4358 | [#5] imaging-gaussian-blur Cycles:10 Average:112.40 Median:113.50 stddev:1.71 (1.5%) stddev-sans-first:1.72 12:38:16 INFO - PID 4358 | Values: 114.0 114.0 110.0 113.0 111.0 114.0 114.0 111.0 113.0 110.0 12:38:16 INFO - PID 4358 | 12:38:16 INFO - PID 4358 | [#6] imaging-darkroom Cycles:10 Average:148.90 Median:149.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 12:38:16 INFO - PID 4358 | Values: 150.0 150.0 148.0 149.0 149.0 148.0 149.0 149.0 149.0 148.0 12:38:16 INFO - PID 4358 | 12:38:16 INFO - PID 4358 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:1.66 (1.8%) stddev-sans-first:1.62 12:38:16 INFO - PID 4358 | Values: 93.0 96.0 99.0 95.0 94.0 94.0 95.0 94.0 94.0 95.0 12:38:16 INFO - PID 4358 | 12:38:16 INFO - PID 4358 | [#8] json-parse-financial Cycles:10 Average:44.50 Median:45.00 stddev:0.97 (2.2%) stddev-sans-first:1.01 12:38:16 INFO - PID 4358 | Values: 45.0 45.0 45.0 44.0 44.0 43.0 45.0 43.0 45.0 46.0 12:38:16 INFO - PID 4358 | 12:38:16 INFO - PID 4358 | [#9] json-stringify-tinderbox Cycles:10 Average:39.40 Median:39.00 stddev:1.07 (2.8%) stddev-sans-first:0.60 12:38:16 INFO - PID 4358 | Values: 42.0 40.0 39.0 39.0 39.0 39.0 40.0 39.0 39.0 38.0 12:38:16 INFO - PID 4358 | 12:38:16 INFO - PID 4358 | [#10] stanford-crypto-aes Cycles:10 Average:70.50 Median:70.00 stddev:1.84 (2.6%) stddev-sans-first:1.87 12:38:16 INFO - PID 4358 | Values: 69.0 69.0 70.0 70.0 69.0 72.0 74.0 69.0 70.0 73.0 12:38:16 INFO - PID 4358 | 12:38:16 INFO - PID 4358 | [#11] stanford-crypto-ccm Cycles:10 Average:105.30 Median:106.50 stddev:3.56 (3.3%) stddev-sans-first:3.77 12:38:16 INFO - PID 4358 | Values: 105.0 101.0 108.0 108.0 102.0 102.0 112.0 108.0 103.0 104.0 12:38:16 INFO - PID 4358 | 12:38:16 INFO - PID 4358 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.50 Median:153.00 stddev:3.87 (2.5%) stddev-sans-first:3.31 12:38:16 INFO - PID 4358 | Values: 158.0 154.0 148.0 147.0 150.0 152.0 155.0 147.0 155.0 149.0 12:38:16 INFO - PID 4358 | 12:38:16 INFO - PID 4358 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.40 Median:60.50 stddev:4.43 (7.3%) stddev-sans-first:4.69 12:38:16 INFO - PID 4358 | Values: 61.0 61.0 60.0 59.0 56.0 72.0 58.0 57.0 59.0 61.0 12:38:16 INFO - PID 4358 | -------- Summary: end -------- 12:38:16 INFO - PID 4358 | 12:38:16 INFO - PID 4358 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:38:17 INFO - TEST-INFO | 4358: exit 0 12:38:17 INFO - TEST-OK | kraken | took 59092ms 12:38:17 INFO - SUITE-END | took 1169s 12:38:17 INFO - Completed test suite (00:19:30) 12:38:17 INFO - javascript benchmark 12:38:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9547.753034586773, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [4043.2924238258297, 4045.332520134178, 4055.7160998730083, 4071.7149799514027, 4078.6533144682708, 6570.055973386617, 6642.734290628381, 6709.932900670992, 6727.899081513775, 6730.125083739107, 6654.767083152091, 6660.367265109807, 6663.866722665556, 6709.865802683956, 6762.188537375522, 5936.940630593682, 5962.34414214436, 5977.342492325841, 6003.4596886280115, 6033.125196846455, 5898.262717160355, 5945.435183657545, 6006.039033754579, 6019.3980601939675, 6083.939160608404, 6022.397760223987, 6042.093685947121, 6056.0, 6074.665893375863, 6102.420270074358, 5942.108683697436, 5984.431479009487, 6005.669688167149, 6095.024796032636, 6100.23747031621, 5881.735321910482, 5885.764569417218, 5912.645241285493, 5913.024350982126, 5933.9406605934255, 6549.312322206195, 6643.93356066438, 6647.0, 6649.102371179877, 6702.597844129319, 7149.714011439536, 7167.175774785862, 7171.677274522659, 7227.710891564331, 7252.746153884642, 7205.819854503627, 7288.635568221568, 7291.562506249589, 7303.415726741848, 7323.853522929565, 6574.145361103026, 6622.966885165543, 6643.036759669821, 6695.096162018114, 6724.697388617475, 7294.051773269547, 7348.512468099354, 7379.1513975892385, 7395.8151046224275, 7407.111146662327, 7007.404370628554, 7042.7887163385185, 7096.716131354791, 7131.429485641136, 7133.5719856808755, 7127.251638577978, 7192.856142877218, 7206.243344448862, 7272.672729727175, 7301.0, 7069.505134640629, 7075.646217689095, 7090.07828982229, 7107.36033756954, 7123.82190445243, 7186.856262874713, 7209.351158395664, 7232.855342893113, 7266.564006159648, 7279.745208917662, 5308.416074231832, 5317.867053323621, 5318.627696061316, 5386.030514507348, 5394.406615272317, 4774.427068751772, 4805.37530121082, 4818.927716084261, 4835.854924352275, 4890.0, 6692.966535167293, 6716.83207919806, 6744.291849355747, 6750.932490675129, 6777.966110169417, 7129.679164437613, 7212.531185473033, 7288.817779555554, 7300.817479563053, 7308.196098429168, 6588.209414870246, 6633.568818026813, 6638.90041649366, 6676.699548520328, 6719.798406047827, 6048.4858787003595, 6105.786297479655, 6110.297315808739, 6126.050462178369, 6135.84660383494, 7537.321641052332, 7550.0, 7637.968874201912, 7642.388608911274, 7649.04386951631, 7054.788356349318, 7072.186698529735, 7201.3158749918675, 7306.6711997959155, 7311.086114235721, 7520.548767073993, 7539.321461068311, 7540.0, 7541.7360392386, 7542.849143016999, 7080.681369338289, 7104.04095447108, 7105.2894710528535, 7125.5368401053765, 7204.639768011683, 7185.604791736296, 7199.388052015428, 7277.708891644275, 7283.308085731847, 7285.0, 6870.587764734132, 6962.268961758942, 6966.094407726963, 6972.755953541602, 6979.30206979298, 6958.373746362851, 6974.511784175059, 6983.127109111361, 6987.860242795217, 6990.755323563651, 6843.828904277433, 6910.032595436543, 6929.411000065051, 6930.896036559355, 6933.618650974043, 6942.51402401827, 7020.859582808213, 7035.753748618774, 7081.68132434052, 7129.643517823984, 6697.028930805103, 6739.42714869222, 6755.493337999571, 6813.0461735358085, 6851.246362900177], "value": 6596.307411310464, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [992.7666998255409, 1021.3106153346491, 1022.560299071399, 1023.595679706516, 1033.9017793309633, 47843.28235076477, 48528.0, 48593.0, 48631.27053094202, 48772.51227487724, 44523.55476445243, 44624.0, 44669.10661786771, 44701.776491117584, 44804.327935081, 8012.679492820294, 8087.353011759074, 8125.390595705317, 8130.268275855172, 8135.430519863612, 7720.613969301512, 7750.7674769756995, 7781.182975787546, 7782.961085194566, 7785.260400261966, 8468.407211495198, 8471.449355791854, 8480.703175388862, 8484.151584841527, 8554.743357699279, 8420.621072051772, 8472.0, 8485.575721213945, 8487.830243395098, 8489.61796719149, 8550.973883134002, 8603.096674849114, 8614.913850861474, 8633.13668633138, 8635.050144484101, 43594.12811743779, 43844.342334865, 43849.56150438487, 43890.341644875356, 44006.339904901455, 3949.3681011038384, 3976.642102210786, 3978.9602103978877, 3980.4626375439234, 3983.7609743415487, 35729.82135089308, 35852.0, 35934.0, 35936.10159746001, 35953.0, 5379.81170659029, 5407.107827208506, 5445.83662490126, 5447.727613619343, 5457.072297709399, 228612.0, 228652.0, 229125.0, 229231.0, 229547.0, 49363.0, 50048.74975625172, 56420.43579564234, 57301.42698572961, 57474.425255747745, 59681.10478342828, 59739.701301493216, 59761.40238597558, 59897.70051149716, 59973.70013149906, 17917.7312340315, 17949.012804295628, 17988.190531426117, 17993.910030449762, 18095.09524523795, 14575.416983320549, 14890.553283401516, 14942.626434339229, 14995.700085998436, 14999.550013499613, 15644.765328520081, 15747.763783543256, 15861.920690396475, 15878.60303492422, 16019.759703604455, 6098.5426093042315, 6335.081413195153, 6383.585066970632, 6394.360563943662, 6420.775272865428, 1107.7839821234782, 1108.578740078765, 1130.37829193943, 1131.213806404541, 1137.3460260350332, 26889.0, 26911.86544067267, 26980.32549186286, 27190.5921411179, 27457.31356716098, 1236.6042866282703, 1237.2576454127452, 1243.9564615238423, 1246.8254444377794, 1252.843394575678, 13953.860461395256, 14076.366563504462, 14180.149191048364, 14268.357923893449, 14282.928585357215, 307566.0, 308572.0, 309180.0, 309529.0, 311775.0, 62125.0, 62215.688921555105, 62373.37626623766, 62524.0, 62773.686131569964, 1574.9370025198864, 1576.5428025872598, 1592.7371983622804, 1610.9113998729947, 1618.9676206475804, 26156.60765088487, 26244.4751104973, 26385.0, 26577.33556661099, 26666.600000999613, 44029.77985110054, 44307.556924431636, 44398.55601443944, 44409.55590444183, 44459.33311000402, 253494.0, 254110.0, 254344.0, 256576.0, 256625.0, 35489.1127721809, 37727.0, 38994.80502597469, 44399.55600443954, 45082.0, 56225.43774562202, 56305.71847140902, 56714.14928776154, 56885.0, 57103.0, 39885.2022959545, 40607.1878562433, 40705.0, 40745.79627101846, 41239.793801030806, 39436.40845387264, 40177.39733904052, 40283.1943361137, 40462.393064103475, 41059.17881642291, 46004.539954600026, 46679.53320466752, 46883.76558117187, 47144.29283560818, 47160.52839471561, 38287.0, 38347.616523834404, 38348.6165138345, 38467.2306553873, 38515.61484385232, 27408.58887116655, 28118.578221326286, 28349.0, 28510.857445712638, 28932.710672893845], "value": 19869.171506735885, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [634.368889854443, 634.8410003087374, 638.9403509795627, 648.4291852260632, 652.5399593286734, 22924.65613015804, 23254.41863953402, 23921.282361529138, 24133.75866241337, 24165.75834241657, 25906.481870362582, 26853.462930741374, 26872.59691104635, 26896.193114206555, 26944.865275673594, 6708.195016598015, 6711.0, 6860.142482189726, 6869.519133660646, 6946.930530694679, 6907.34380233877, 6910.619915904624, 6914.723411063576, 6957.6869040893025, 6966.4078553323025, 6903.965480172592, 6911.274316196802, 6913.0, 6920.031195632616, 6931.826704332381, 6884.795160846832, 6903.827404314882, 6912.55068420551, 6927.58434493932, 6928.203256625477, 6476.190476190476, 6484.1894763154605, 6489.994050922068, 6495.4803615710625, 6510.381513756186, 29832.403351933055, 31773.36453270945, 31970.680293197, 32011.519827202377, 32020.359592808247, 2299.2642354446584, 2330.254318618043, 2333.0317918063984, 2337.5558643857785, 2345.519168570439, 20716.274930377516, 20844.0, 21297.574048519098, 21475.67786483204, 21509.56980860374, 2539.987300063506, 2567.5121726871835, 2579.509893120301, 2598.4413351129597, 2612.568926127187, 147294.0, 152615.0, 173526.0, 175952.0, 176648.0, 23407.180748674054, 23904.0, 24326.270211893672, 25505.744942550336, 25603.61594576083, 49763.75118124459, 50049.74776135082, 50106.49893501091, 50374.244386334234, 50530.98938021292, 13024.348782560834, 13194.472221111047, 13351.399187036606, 13436.328183590782, 13465.326733663278, 10756.515956781963, 10834.241603087867, 10837.0, 10856.077233435246, 10857.294275872044, 12482.126251162514, 12688.432164420801, 12756.872431275755, 12843.357832108357, 12907.354632268349, 3813.790241536742, 4217.97891010543, 4361.890952726208, 4480.633406810889, 4506.369108324837, 1054.752133248686, 1059.708580140452, 1065.2702898514542, 1071.0521188748014, 1071.276888100529, 20403.285884993955, 21352.252671156435, 21643.35069947904, 21701.34895953124, 21907.233246836284, 1113.2318700096907, 1126.7070561653866, 1127.8421021057059, 1128.114430172311, 1139.5897476908306, 11731.530738770354, 11805.52777888892, 11878.524859005716, 11885.584004559973, 11915.88084119165, 579169.0, 586068.0, 590934.0, 623033.0, 625464.0, 135924.32037839945, 136783.31608342094, 137080.0, 137698.31150844382, 138869.0, 1255.7049093463029, 1273.1597145883673, 1298.4935875008755, 1301.804729290614, 1306.8693130686854, 18848.0, 19335.51661208481, 19368.612627747083, 19406.514837129183, 19530.804691952897, 66307.33692663012, 66620.6668966652, 66866.66566667135, 67084.6645766768, 67340.6632966832, 83177.58411207906, 84549.0, 85077.14922850693, 86212.0, 86881.0, 21452.141914323878, 21554.0, 21727.34817955464, 21840.78159218451, 21934.45163870916, 24446.388840279134, 24525.38686532851, 24651.876740616182, 24659.753402466464, 25224.873875630503, 19859.404217873485, 20040.89979550093, 20060.69908951338, 20170.092345844765, 20197.59604807925, 17642.149243586482, 17680.469585912444, 17782.0, 18425.907870460564, 18515.166817493515, 19004.144813483705, 19090.33183838559, 19098.56760742922, 19125.713114303573, 19518.707219391436, 20692.068856901173, 20800.68798968047, 20866.37400877976, 20927.37217883405, 20978.37064888056, 18266.634667306844, 18418.35535756242, 18801.717974230123, 18979.810201897802, 19148.425547233604], "value": 14029.466487152575, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1458.60617633239, 1500.609841441225, 1512.1683074309128, 1525.1840265457975, 1538.323137819359, 17372.391966281182, 18054.097295135256, 18139.0, 18145.45563633093, 18145.54636134094, 17741.37905173319, 17849.7322540162, 17886.195121219545, 17905.462836114904, 17914.193861276242, 3086.27472543952, 3123.5158550424662, 3173.1591128351006, 3176.618805743314, 3178.698023687746, 3083.784135110543, 3112.2219445138717, 3113.377324535091, 3119.282565010049, 3134.796238244518, 2887.1627228103825, 2996.4905965985727, 2997.355568552761, 3052.13014290926, 3070.5547695584128, 3102.4260511805387, 3135.4826453635123, 3167.3506931079305, 3167.5565420840985, 3169.524571314298, 2982.835944023078, 2998.055612482061, 2998.0705981145697, 2999.2052106191877, 3020.275133967832, 17114.315427383015, 17245.137743112795, 17253.827461725472, 17338.56653583658, 17364.65270694579, 8554.572271386405, 8770.386072974894, 8782.692605758768, 8839.734807955772, 8866.379353445262, 5453.918191227135, 5519.2824932758485, 5602.831915042555, 5743.167240750068, 5773.9422605773825, 2083.624947509448, 2086.3010891351414, 2112.1340250497374, 2118.7457505099333, 2159.276642324823, 32108.0, 32551.51172732411, 32659.510107348407, 32705.67294327074, 32711.6728832711, 7710.884336734954, 7719.112302085332, 7727.265909738566, 7921.564313962672, 7940.205979402013, 20385.184592616424, 20465.897670511553, 20545.58908821845, 20552.588948220953, 20562.897185513975, 8259.0, 8394.160583941679, 8604.48373097616, 8802.07578204292, 8894.4663320201, 7691.30778229962, 7876.448648594658, 7985.081715602664, 8082.515049096955, 8091.0695270043525, 5911.142884281841, 6046.425589568984, 6050.727717252734, 6096.573239873166, 6105.45050945417, 1743.7645917801187, 1747.1700942052423, 1758.5164079878136, 1763.078244713243, 1798.2896755781655, 826.9007719073629, 1010.722051435861, 1028.861103750999, 1030.7886883188928, 1044.5456226541328, 9749.71143612366, 9770.31607787448, 9909.157721593601, 9931.354461959949, 10422.0, 810.1372038778682, 829.4442723375353, 849.5285116760257, 861.9353548483889, 927.7216834949623, 6357.0, 6632.336766323329, 6635.568688035262, 6687.164104486939, 6688.632125233159, 25385.492290154463, 27311.590326144727, 27525.0, 27527.0, 27553.31116722098, 26262.343441413734, 26727.732722672525, 26743.331416714736, 27341.863290683817, 27375.17874463769, 12545.93727031359, 12548.435320410603, 12552.497900083894, 12595.93702031484, 12654.114212005255, 9021.729348119567, 9250.121238482334, 9363.531823408939, 9414.717558473258, 9472.810543788948, 21977.90110494437, 22524.09903603903, 22840.771592283865, 22961.770382295963, 23193.072277108728, 23417.41456463534, 23501.0, 23751.881240593688, 23790.40523986845, 23822.16622418207, 12151.574694885636, 12155.392230388268, 12347.75304493923, 12381.876181238073, 12418.689532761753, 14975.0, 15049.774253386428, 15155.090694558361, 15155.242237887842, 15175.392984280505, 12949.482020719443, 13283.136596121607, 13303.135296205715, 13487.25820079905, 13527.256000920044, 12658.0, 12788.808167877303, 12835.42240599194, 13124.343782811013, 13152.0, 11623.76752464963, 11720.472578734149, 11769.588064417705, 11798.469068892093, 11858.703532411759, 8140.796480088045, 8149.429539932385, 8265.0, 8294.958525207336, 8317.875231871405, 8420.536870471939, 8442.366822488215, 8476.618552165291, 8515.361347899056, 8586.570671466527], "value": 7636.936538027366, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.0556784090172, 575.4561938967677, 582.2663444060482, 599.7810799058344, 600.2287061126453, 6241.719122639481, 6441.516886233528, 6462.450691691212, 6471.4175724184815, 6508.54440189187, 6284.497240220783, 6329.398707122816, 6345.143405640249, 6361.9363806362035, 6417.935820641792, 5224.63427560071, 5250.291210686566, 5294.444083371249, 5304.20436934459, 5306.7346632668405, 5149.391294791179, 5180.922286165711, 5199.324087868572, 5271.762770675329, 5331.493508116722, 5320.414754377015, 5351.866203344908, 5361.51746342829, 5362.51737343639, 5387.434319396466, 5281.0, 5349.438308977559, 5349.973250133725, 5350.652207606532, 5369.865753356158, 5013.799448022075, 5019.121653710623, 5040.672356296829, 5041.546260836542, 5094.668846525001, 6343.238811342622, 6398.7760428385245, 6403.111082230706, 6419.903701444436, 6423.164988551505, 6262.718177682015, 6336.778212762577, 6383.329750376237, 6388.137601423795, 6400.647964361959, 6824.7952561423235, 6838.0, 6912.412444942186, 6920.826979325507, 6933.394634789932, 5381.596380271495, 5560.833175004756, 5601.60788744788, 5632.35227948787, 5635.09838425854, 6989.7553585624255, 7118.0, 7138.250483699188, 7155.821104472378, 7184.389326907271, 5851.444112809361, 5855.3851845556455, 5862.179294898718, 5874.765009399576, 5879.118132280192, 5929.940700593025, 5936.5547583931375, 5944.613600115978, 5957.851053723692, 6012.428819262163, 6228.252609686867, 6337.302896681362, 6354.110424540568, 6368.745250189941, 6369.840753981095, 6862.9313706863295, 6865.759698410531, 6914.896276555855, 6917.723291068301, 6927.930720692729, 2025.5543780368296, 2047.3653167518116, 2047.805458481442, 2059.9588008240053, 2070.1201989154547, 1515.143943671822, 1549.3957356630922, 1557.4393218441353, 1564.4837203722743, 1574.850389213023, 6091.634501929899, 6110.755569777248, 6198.6280823149855, 6236.563440559208, 6300.873982520324, 6795.2185498667295, 6823.181218253742, 6824.829379265459, 6920.273371296042, 6922.826929326807, 6244.313125556185, 6261.342559031327, 6281.277653069864, 6310.403070249913, 6342.207224096988, 3616.258666973264, 3632.473291372736, 3641.9817900910325, 3650.251698401821, 3665.3872296189593, 7100.573965562083, 7156.284371562802, 7207.675654595556, 7217.422606191492, 7247.601381924045, 7010.649467526603, 7023.578585284899, 7057.8707406814665, 7078.256783037707, 7085.256048114977, 7162.630795215019, 7198.280171982759, 7215.927840721631, 7229.987801707765, 7297.233790451926, 6877.449804015767, 6882.174139103339, 6905.274946130584, 6921.757738479129, 6948.9305106950305, 6718.798436046926, 6785.760698969229, 6810.897836532356, 6818.590884546943, 6862.622555759481, 7199.400299850075, 7212.764043146642, 7227.421806255592, 7230.710771569078, 7265.709371625076, 5797.0, 5815.0, 5815.76093204111, 5848.561357898089, 5854.385289544536, 6726.831829204309, 6915.135608048994, 6982.217181230873, 6984.825379365353, 7007.8086725257845, 6648.235452922976, 6726.260111387841, 6758.121444212417, 6758.357955994173, 6762.6618669065365, 6621.205455345389, 6636.279125558498, 6648.800535983928, 6690.665466726547, 6700.597964122168, 6489.675516224264, 6546.312637173029, 6718.563293385914, 6723.831904202239, 6731.730730770714, 6643.30245324234, 6706.463482921452, 6730.562513436611, 6749.93250067493, 6851.3491218334175, 6532.510061745293, 6535.3137920517665, 6541.280459149585, 6558.409743123142, 6593.637349945607], "value": 5451.842636575667, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1010.1666125446513, 1015.6699072801339, 1023.3808545829772, 1035.6271742172821, 1039.340019087879, 19342.12960416781, 19655.901720491394, 19697.70453443196, 19701.901490492546, 19711.112999915003, 18529.536761580934, 18610.53473663156, 18713.625727485443, 18728.812711872877, 18832.152553135107, 1063.6383629565946, 1073.6295977887655, 1076.7092884921067, 1078.250615822004, 1084.3331351219022, 1073.404260635347, 1076.580133747839, 1079.7084787107476, 1081.3349789879246, 1082.2965072702727, 1091.1870656361023, 1133.0822034152322, 1140.344302026334, 1142.7371704507968, 1143.3254379915847, 1134.9886501135006, 1138.4706111658118, 1138.698244965085, 1138.9886101138966, 1139.3448766959032, 832.950022998622, 834.1450013735908, 834.3450391442689, 837.9581020948929, 838.4256784102909, 18832.246710131578, 19031.524211894673, 19075.0, 19083.522911927314, 19109.904450477658, 20542.0, 21036.89481552598, 21120.2607908724, 21231.0, 21248.681269780966, 16977.151142442952, 17079.0, 17261.913690431593, 17404.738928916075, 17493.212805423787, 3933.3903244997073, 3956.7032472564674, 3978.3833505806647, 3979.383195604686, 3980.2636512245326, 148590.25704871514, 158270.0, 159065.8186836257, 159332.0, 160749.19625401916, 16098.67802643964, 16278.674426511403, 16406.0, 16527.421540246032, 16559.6688066238, 30063.248418789266, 30520.38959220803, 30614.54078188829, 30631.69368306333, 31059.378812423623, 8954.328425368123, 8955.2835773138, 8958.059403762642, 8975.461472311683, 8977.192052715287, 4650.883727906829, 4728.621710263171, 4742.122707299091, 4743.928841067387, 4756.762161891892, 5527.170924361298, 5811.709414529257, 5894.911576326359, 5897.587168898136, 5915.940840591625, 1168.415792103948, 1207.034372501995, 1218.8598311194326, 1222.4743360355133, 1225.2893321873291, 749.4566439331442, 769.5459678789541, 774.0556521044317, 777.8405426887474, 791.6595863778515, 12816.935915320364, 13368.99732520065, 13845.653858653413, 14223.573292801233, 14377.496787612383, 771.5139462138817, 772.6600295869912, 773.0954782904016, 776.2664282253216, 788.9289963903276, 6216.347283535254, 6261.624302541862, 6299.6535190564955, 6388.616682999035, 6395.6482393468805, 154841.0, 156435.21782391015, 156823.0, 157698.0, 161901.0, 128546.0, 129547.0, 132333.0, 132501.0, 135851.0, 119936.0, 120370.0, 120639.39680301542, 120683.0, 122245.38877305557, 11821.0, 11995.940020300191, 12011.339376334385, 12191.939040304742, 12399.318037508023, 58746.4125358758, 59107.70446147742, 59793.80412391641, 60568.39431605628, 60838.0, 73886.0, 74342.0, 74342.0, 76614.0, 78285.0, 17467.12664366751, 17603.735943960593, 17685.38101166453, 17951.281948721906, 17972.730409044136, 18151.364702235358, 18483.353082642043, 18487.35294264748, 18581.90709046446, 18693.719594205824, 18284.268629254682, 18373.908130459262, 18376.173072211513, 18482.81517184811, 18495.53761155928, 16553.503394898173, 16804.831951680328, 16899.408520701716, 16987.235574398936, 17000.48998530046, 16340.346386144896, 16439.753403698716, 16613.50159495217, 16725.749113763548, 16796.832031679525, 13672.0, 13767.655808604864, 13877.86122138806, 13900.443982240597, 13924.094933829268, 13412.262325572186, 13546.0, 13663.590092297247, 13780.310984450938, 13785.655358616115], "value": 9894.563011237773, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1361.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 105.0, 106.0, 105.0, 104.0, 107.0, 106.0, 106.0, 105.0, 105.0], "value": 105.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 111.0, 110.0, 112.0, 120.0, 113.0, 111.0, 112.0, 142.0, 112.0], "value": 116.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 160.0, 162.0, 160.0, 161.0, 161.0, 163.0, 162.0, 165.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 73.0, 72.0, 72.0, 74.0, 73.0, 78.0, 73.0, 73.0], "value": 73.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 77.0, 77.0, 76.0, 82.0, 77.0, 76.0, 76.0, 77.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 110.0, 113.0, 111.0, 114.0, 114.0, 111.0, 113.0, 110.0], "value": 112.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 148.0, 149.0, 149.0, 148.0, 149.0, 149.0, 149.0, 148.0], "value": 148.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 99.0, 95.0, 94.0, 94.0, 95.0, 94.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 45.0, 45.0, 44.0, 44.0, 43.0, 45.0, 43.0, 45.0, 46.0], "value": 44.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [42.0, 40.0, 39.0, 39.0, 39.0, 39.0, 40.0, 39.0, 39.0, 38.0], "value": 39.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 70.0, 70.0, 69.0, 72.0, 74.0, 69.0, 70.0, 73.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 101.0, 108.0, 108.0, 102.0, 102.0, 112.0, 108.0, 103.0, 104.0], "value": 105.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 154.0, 148.0, 147.0, 150.0, 152.0, 155.0, 147.0, 155.0, 149.0], "value": 151.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 60.0, 59.0, 56.0, 72.0, 58.0, 57.0, 59.0, 61.0], "value": 60.4, "unit": "score"}]}]} 12:38:17 INFO - Return code: 0 12:38:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:38:17 INFO - # TBPL SUCCESS # 12:38:17 INFO - Running post-action listener: _package_coverage_data 12:38:17 INFO - Running post-action listener: _resource_record_post_action 12:38:17 INFO - [mozharness: 2017-09-28 19:38:17.812131Z] Finished run-tests step (success) 12:38:17 INFO - Running post-run listener: _resource_record_post_run 12:38:17 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 10784768; Write bytes: 414294016; Read time: 4880; Write time: 358344 12:38:17 INFO - TinderboxPrint: CPU usage
13.1% 12:38:17 INFO - TinderboxPrint: I/O read bytes / time
10,784,768 / 4,880 12:38:17 INFO - TinderboxPrint: I/O write bytes / time
414,294,016 / 358,344 12:38:17 INFO - TinderboxPrint: CPU idle
8,210.5 (86.9%) 12:38:17 INFO - TinderboxPrint: CPU user
1,179.4 (12.5%) 12:38:17 INFO - TinderboxPrint: Swap in / out
0 / 0 12:38:17 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 1515520; Read time: 0; Write time: 92 12:38:17 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:38:17 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 9039872; Write bytes: 412778496; Read time: 4836; Write time: 358252 12:38:18 INFO - Running post-run listener: _upload_blobber_files 12:38:18 INFO - Blob upload gear active. 12:38:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:38:18 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 12:38:18 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', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:38:18 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 Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:38:18 INFO - (blobuploader) - INFO - Open directory for files ... 12:38:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:38:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:38:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:38:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:38:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:38:19 INFO - (blobuploader) - INFO - Done attempting. 12:38:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:38:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:38:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:38:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:38:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:38:24 INFO - (blobuploader) - INFO - Done attempting. 12:38:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:38:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:38:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:38:25 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:38:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:38:25 INFO - (blobuploader) - INFO - Done attempting. 12:38:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:38:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:38:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:38:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:38:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:38:26 INFO - (blobuploader) - INFO - Done attempting. 12:38:26 INFO - (blobuploader) - INFO - Iteration through files over. 12:38:26 INFO - Return code: 0 12:38:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:38:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:38:26 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/54fa6ed399665f78c6d429755ae0c233b44f4e4e894a060ced23baee4d1a2e425057321610be1299844046e923597f936ef3971facf018a49d23ec956d1ff3e9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ff3bc71e119ac37a612f2c1a87b91b848ab9b5f83caaa2fba116ab4701e1e430b282200020afcaf123b6498c2a11ff572c175894f9baa232d82a39fe5c78efbf", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3c3c9ef0621b9de278cdd8d6e340d4fc6062ab9d27efadc8ca7ca89e44481d348412d4d29d3be081c4cd006398d5646da69791ace146744741282e6e02e6163c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/458d1e4275b0927e0261aaff49d4ff6069e1a50af4c5f854df3486f1faf301502ff07093826aac7e69b8216335bba3d6931578e1e2712791eb7a02c5ab01cda1"} 12:38:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:38:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:38:26 INFO - Contents: 12:38:26 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/54fa6ed399665f78c6d429755ae0c233b44f4e4e894a060ced23baee4d1a2e425057321610be1299844046e923597f936ef3971facf018a49d23ec956d1ff3e9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ff3bc71e119ac37a612f2c1a87b91b848ab9b5f83caaa2fba116ab4701e1e430b282200020afcaf123b6498c2a11ff572c175894f9baa232d82a39fe5c78efbf", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3c3c9ef0621b9de278cdd8d6e340d4fc6062ab9d27efadc8ca7ca89e44481d348412d4d29d3be081c4cd006398d5646da69791ace146744741282e6e02e6163c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/458d1e4275b0927e0261aaff49d4ff6069e1a50af4c5f854df3486f1faf301502ff07093826aac7e69b8216335bba3d6931578e1e2712791eb7a02c5ab01cda1"} 12:38:26 INFO - Running post-run listener: copy_logs_to_upload_dir 12:38:26 INFO - Copying logs to upload dir... 12:38:26 INFO - mkdir: /builds/slave/test/build/upload/logs 12:38:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.188762 ========= master_lag: 0.54 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2017-09-28 12:38:26.644038) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-28 12:38:26.648663) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/54fa6ed399665f78c6d429755ae0c233b44f4e4e894a060ced23baee4d1a2e425057321610be1299844046e923597f936ef3971facf018a49d23ec956d1ff3e9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ff3bc71e119ac37a612f2c1a87b91b848ab9b5f83caaa2fba116ab4701e1e430b282200020afcaf123b6498c2a11ff572c175894f9baa232d82a39fe5c78efbf", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3c3c9ef0621b9de278cdd8d6e340d4fc6062ab9d27efadc8ca7ca89e44481d348412d4d29d3be081c4cd006398d5646da69791ace146744741282e6e02e6163c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/458d1e4275b0927e0261aaff49d4ff6069e1a50af4c5f854df3486f1faf301502ff07093826aac7e69b8216335bba3d6931578e1e2712791eb7a02c5ab01cda1"} build_url:https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011048 build_url: 'https://queue.taskcluster.net/v1/task/D8Pxxm9sS2eD_9v1y_8bOA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/54fa6ed399665f78c6d429755ae0c233b44f4e4e894a060ced23baee4d1a2e425057321610be1299844046e923597f936ef3971facf018a49d23ec956d1ff3e9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ff3bc71e119ac37a612f2c1a87b91b848ab9b5f83caaa2fba116ab4701e1e430b282200020afcaf123b6498c2a11ff572c175894f9baa232d82a39fe5c78efbf", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3c3c9ef0621b9de278cdd8d6e340d4fc6062ab9d27efadc8ca7ca89e44481d348412d4d29d3be081c4cd006398d5646da69791ace146744741282e6e02e6163c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/458d1e4275b0927e0261aaff49d4ff6069e1a50af4c5f854df3486f1faf301502ff07093826aac7e69b8216335bba3d6931578e1e2712791eb7a02c5ab01cda1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-28 12:38:26.679916) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 12:38:26.680269) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1506625544.420549-1836055820 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004433 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 12:38:26.762420) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-28 12:38:26.762734) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-28 12:38:26.763042) ========= ========= Total master_lag: 0.75 =========