builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-069 starttime: 1506959291.88 results: success (0) revision: 35bae846b62970fc2faa9c0979c2544eff3c04da ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-02 08:48:11.878261) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-02 08:48:11.878703) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-02 08:48:11.878978) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-02 08:48:12.268111) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 08:48:12.268452) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-02 08:48:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 95.2M=0s 2017-10-02 08:48:12 (95.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.244868 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 08:48:12.567413) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 08:48:12.567738) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018223 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 08:48:12.621608) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-02 08:48:12.621949) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 35bae846b62970fc2faa9c0979c2544eff3c04da --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 35bae846b62970fc2faa9c0979c2544eff3c04da --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=05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-02 08:48:12,935 truncating revision to first 12 chars 2017-10-02 08:48:12,935 Setting DEBUG logging. 2017-10-02 08:48:12,935 attempt 1/10 2017-10-02 08:48:12,935 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/35bae846b629?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-02 08:48:14,195 unpacking tar archive at: mozilla-beta-35bae846b629/testing/mozharness/ program finished with exit code 0 elapsedTime=1.595396 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-02 08:48:14.238233) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-02 08:48:14.238567) ========= script_repo_revision: 35bae846b62970fc2faa9c0979c2544eff3c04da ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-02 08:48:14.242260) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-02 08:48:14.242704) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-02 08:48:14.266745) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-10-02 08:48:14.267201) ========= /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=05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:48:14 INFO - MultiFileLogger online at 20171002 08:48:14 in /builds/slave/test 08:48:14 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 08:48:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:48:14 INFO - {'append_to_log': False, 08:48:14 INFO - 'base_work_dir': '/builds/slave/test', 08:48:14 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 08:48:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:48:14 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 08:48:14 INFO - 'buildbot_json_path': 'buildprops.json', 08:48:14 INFO - 'code_coverage': False, 08:48:14 INFO - 'config_files': ('talos/linux_config.py',), 08:48:14 INFO - 'default_actions': ('clobber', 08:48:14 INFO - 'read-buildbot-config', 08:48:14 INFO - 'download-and-extract', 08:48:14 INFO - 'populate-webroot', 08:48:14 INFO - 'create-virtualenv', 08:48:14 INFO - 'install', 08:48:14 INFO - 'setup-mitmproxy', 08:48:14 INFO - 'run-tests'), 08:48:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:48:14 INFO - 'disable_ccov_upload': False, 08:48:14 INFO - 'disable_stylo': False, 08:48:14 INFO - 'download_minidump_stackwalk': True, 08:48:14 INFO - 'download_symbols': 'ondemand', 08:48:14 INFO - 'enable_stylo': False, 08:48:14 INFO - 'enable_webrender': False, 08:48:14 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 08:48:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:48:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:48:14 INFO - 'gecko_profile': False, 08:48:14 INFO - 'gecko_profile_interval': 0, 08:48:14 INFO - 'installer_path': 'installer.exe', 08:48:14 INFO - 'log_level': 'info', 08:48:14 INFO - 'log_name': 'talos', 08:48:14 INFO - 'log_to_console': True, 08:48:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:48:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:48:14 INFO - 'opt_config_files': (), 08:48:14 INFO - 'pip_index': False, 08:48:14 INFO - 'suite': 'dromaeojs-e10s', 08:48:14 INFO - 'system_bits': '32', 08:48:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:48:14 INFO - 'title': 'talos-linux64-ix-069', 08:48:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:48:14 INFO - 'use_talos_json': True, 08:48:14 INFO - 'verify': 'False', 08:48:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:48:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:48:14 INFO - 'work_dir': 'build'} 08:48:14 INFO - [mozharness: 2017-10-02 15:48:14.818312Z] Running clobber step. 08:48:14 INFO - Running pre-action listener: _resource_record_pre_action 08:48:14 INFO - Running main action method: clobber 08:48:14 INFO - rmtree: /builds/slave/test/build 08:48:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:48:15 INFO - Running post-action listener: _resource_record_post_action 08:48:15 INFO - [mozharness: 2017-10-02 15:48:15.772156Z] Finished clobber step (success) 08:48:15 INFO - [mozharness: 2017-10-02 15:48:15.772253Z] Running read-buildbot-config step. 08:48:15 INFO - Running pre-action listener: _resource_record_pre_action 08:48:15 INFO - Running main action method: read_buildbot_config 08:48:15 INFO - Using buildbot properties: 08:48:15 INFO - { 08:48:15 INFO - "project": "", 08:48:15 INFO - "product": "firefox", 08:48:15 INFO - "who": "ryanvm@gmail.com", 08:48:15 INFO - "installer_path": "public/build/target.tar.bz2", 08:48:15 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 08:48:15 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta", 08:48:15 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs-e10s", 08:48:15 INFO - "stage_platform": "linux64", 08:48:15 INFO - "basedir": "/builds/slave/test", 08:48:15 INFO - "buildnumber": 146, 08:48:15 INFO - "platform": "ubuntu64_hw", 08:48:15 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:48:15 INFO - "slavebuilddir": "test", 08:48:15 INFO - "taskId": "YYrlgCdTSpSyFvF1mgfbnQ", 08:48:15 INFO - "branch": "mozilla-beta", 08:48:15 INFO - "script_repo_revision": "production", 08:48:15 INFO - "revision": "35bae846b62970fc2faa9c0979c2544eff3c04da", 08:48:15 INFO - "slavename": "talos-linux64-ix-069", 08:48:15 INFO - "repo_path": "releases/mozilla-beta" 08:48:15 INFO - } 08:48:15 INFO - Finding installer, test and symbols from parent task. 08:48:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YYrlgCdTSpSyFvF1mgfbnQ'}, attempt #1 08:48:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YYrlgCdTSpSyFvF1mgfbnQ'}, attempt #1 08:48:16 INFO - Task dependencies: d3dkFuYKSOCZAIW9aj6NGQ 08:48:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d3dkFuYKSOCZAIW9aj6NGQ'}, attempt #1 08:48:17 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/d3dkFuYKSOCZAIW9aj6NGQ/artifacts/public/build/target.tar.bz2 08:48:17 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/d3dkFuYKSOCZAIW9aj6NGQ/artifacts/public/build/target.test_packages.json 08:48:17 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/d3dkFuYKSOCZAIW9aj6NGQ/artifacts/public/build/target.crashreporter-symbols.zip 08:48:17 INFO - Running post-action listener: _resource_record_post_action 08:48:17 INFO - [mozharness: 2017-10-02 15:48:17.190515Z] Finished read-buildbot-config step (success) 08:48:17 INFO - [mozharness: 2017-10-02 15:48:17.190828Z] Running download-and-extract step. 08:48:17 INFO - Running pre-action listener: _resource_record_pre_action 08:48:17 INFO - Running main action method: download_and_extract 08:48:17 INFO - mkdir: /builds/slave/test/build/tests 08:48: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')]} 08:48:17 INFO - trying https://queue.taskcluster.net/v1/task/d3dkFuYKSOCZAIW9aj6NGQ/artifacts/public/build/target.test_packages.json 08:48:17 INFO - Downloading https://queue.taskcluster.net/v1/task/d3dkFuYKSOCZAIW9aj6NGQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:48:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d3dkFuYKSOCZAIW9aj6NGQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:48:17 INFO - Downloaded 989 bytes. 08:48:17 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:48:17 INFO - Using the following test package requirements: 08:48:17 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:48:17 INFO - u'common': [u'target.common.tests.zip'], 08:48:17 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:48:17 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:48:17 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:48:17 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:48:17 INFO - u'mozbase': [u'target.common.tests.zip'], 08:48:17 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:48:17 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:48:17 INFO - u'web-platform': [u'target.common.tests.zip', 08:48:17 INFO - u'target.web-platform.tests.tar.gz'], 08:48:17 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:48:17 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 08:48:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d3dkFuYKSOCZAIW9aj6NGQ/artifacts/public/build/target.common.tests.zip 08:48:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d3dkFuYKSOCZAIW9aj6NGQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:48:17 INFO - Fetch https://queue.taskcluster.net/v1/task/d3dkFuYKSOCZAIW9aj6NGQ/artifacts/public/build/target.common.tests.zip into memory 08:48:20 INFO - Content-Length response header: 55180123 08:48:20 INFO - Bytes received: 55180123 08:48:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d3dkFuYKSOCZAIW9aj6NGQ/artifacts/public/build/target.talos.tests.zip 08:48:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d3dkFuYKSOCZAIW9aj6NGQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:48:31 INFO - Fetch https://queue.taskcluster.net/v1/task/d3dkFuYKSOCZAIW9aj6NGQ/artifacts/public/build/target.talos.tests.zip into memory 08:48:32 INFO - Content-Length response header: 14509184 08:48:32 INFO - Bytes received: 14509184 08:48:33 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')]} 08:48:33 INFO - trying https://queue.taskcluster.net/v1/task/d3dkFuYKSOCZAIW9aj6NGQ/artifacts/public/build/target.tar.bz2 08:48:33 INFO - Downloading https://queue.taskcluster.net/v1/task/d3dkFuYKSOCZAIW9aj6NGQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:48:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d3dkFuYKSOCZAIW9aj6NGQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:48:34 INFO - Downloaded 53565870 bytes. 08:48:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d3dkFuYKSOCZAIW9aj6NGQ/artifacts/public/build/target.tar.bz2 08:48:34 INFO - mkdir: /builds/slave/test/properties 08:48:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:48:34 INFO - Writing to file /builds/slave/test/properties/build_url 08:48:34 INFO - Contents: 08:48:34 INFO - build_url:https://queue.taskcluster.net/v1/task/d3dkFuYKSOCZAIW9aj6NGQ/artifacts/public/build/target.tar.bz2 08:48:34 INFO - Running post-action listener: _resource_record_post_action 08:48:34 INFO - Running post-action listener: find_tests_for_verification 08:48:34 INFO - Running post-action listener: set_extra_try_arguments 08:48:34 INFO - [mozharness: 2017-10-02 15:48:34.828205Z] Finished download-and-extract step (success) 08:48:34 INFO - [mozharness: 2017-10-02 15:48:34.828302Z] Running populate-webroot step. 08:48:34 INFO - Running pre-action listener: _resource_record_pre_action 08:48:34 INFO - Running main action method: populate_webroot 08:48:34 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:48:34 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:48:34 INFO - 'tests': ['tresize', 08:48:34 INFO - 'tcanvasmark']}, 08:48:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:48:34 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:48:34 INFO - 'tests': ['dromaeo_css', 08:48:34 INFO - 'kraken']}, 08:48:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:48:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:48:34 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:48:34 INFO - 'talos_options': ['--disable-stylo'], 08:48:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:48:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:48:34 INFO - 'tests': ['damp', 'tps']}, 08:48:34 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:48:34 INFO - 'talos_options': ['--disable-stylo'], 08:48:34 INFO - 'tests': ['damp', 'tps']}, 08:48:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:48:34 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:48:34 INFO - 'tests': ['dromaeo_dom']}, 08:48:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:48:34 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:48:34 INFO - 'tests': ['basic_compositor_video', 08:48:34 INFO - 'glvideo']}, 08:48:34 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:48:34 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:48:34 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:48:34 INFO - 'talos_options': ['--disable-stylo'], 08:48:34 INFO - 'tests': ['ts_paint_webext', 08:48:34 INFO - 'tp5o_webext']}, 08:48:34 INFO - 'other-e10s': {'tests': ['a11yr', 08:48:34 INFO - 'ts_paint', 08:48:34 INFO - 'tpaint', 08:48:34 INFO - 'sessionrestore', 08:48:34 INFO - 'sessionrestore_many_windows', 08:48:34 INFO - 'sessionrestore_no_auto_restore', 08:48:34 INFO - 'tabpaint']}, 08:48:34 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:48:34 INFO - 'tests': ['a11yr', 08:48:34 INFO - 'ts_paint', 08:48:34 INFO - 'tpaint', 08:48:34 INFO - 'sessionrestore', 08:48:34 INFO - 'sessionrestore_many_windows', 08:48:34 INFO - 'sessionrestore_no_auto_restore', 08:48:34 INFO - 'tabpaint']}, 08:48:34 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 08:48:34 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 08:48:34 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:48:34 INFO - 'tests': ['perf_reftest_singletons']}, 08:48:34 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:48:34 INFO - 'tests': ['bloom_basic']}, 08:48:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:48:34 INFO - 'tsvgr_opacity', 08:48:34 INFO - 'tart', 08:48:34 INFO - 'tscrollx', 08:48:34 INFO - 'tsvg_static']}, 08:48:34 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:48:34 INFO - 'tests': ['tsvgx', 08:48:34 INFO - 'tsvgr_opacity', 08:48:34 INFO - 'tart', 08:48:34 INFO - 'tscrollx', 08:48:34 INFO - 'tsvg_static']}, 08:48:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:48:34 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:48:34 INFO - 'talos_options': ['--disable-stylo'], 08:48:34 INFO - 'tests': ['tp5o']}, 08:48:34 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:48:34 INFO - 'talos_options': ['--mitmproxy', 08:48:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:48:34 INFO - '--firstNonBlankPaint'], 08:48:34 INFO - 'tests': ['tp6_google', 08:48:34 INFO - 'tp6_youtube', 08:48:34 INFO - 'tp6_amazon', 08:48:34 INFO - 'tp6_facebook']}, 08:48:34 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:48:34 INFO - 'talos_options': ['--disable-stylo', 08:48:34 INFO - '--mitmproxy', 08:48:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:48:34 INFO - '--firstNonBlankPaint'], 08:48:34 INFO - 'tests': ['tp6_google', 08:48:34 INFO - 'tp6_youtube', 08:48:34 INFO - 'tp6_amazon', 08:48:34 INFO - 'tp6_facebook']}, 08:48:34 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:48:34 INFO - 'talos_options': ['--stylo-threads=1', 08:48:34 INFO - '--mitmproxy', 08:48:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:48:34 INFO - '--firstNonBlankPaint'], 08:48:34 INFO - 'tests': ['tp6_google', 08:48:34 INFO - 'tp6_youtube', 08:48:34 INFO - 'tp6_amazon', 08:48:34 INFO - 'tp6_facebook']}, 08:48:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:48:34 INFO - 'talos_options': ['--xperf_path', 08:48:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:48:34 INFO - 'tests': ['tp5n']}, 08:48:34 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:48:34 INFO - 'talos_options': ['--disable-stylo', 08:48:34 INFO - '--xperf_path', 08:48:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:48:34 INFO - 'tests': ['tp5n']}}} 08:48:34 INFO - Running post-action listener: _resource_record_post_action 08:48:34 INFO - [mozharness: 2017-10-02 15:48:34.843460Z] Finished populate-webroot step (success) 08:48:34 INFO - [mozharness: 2017-10-02 15:48:34.843562Z] Running create-virtualenv step. 08:48:34 INFO - Running pre-action listener: _resource_record_pre_action 08:48:34 INFO - Running main action method: create_virtualenv 08:48:34 INFO - Creating virtualenv /builds/slave/test/build/venv 08:48:34 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:48:34 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 08:48:34 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 08:48:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:48:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:48:36 INFO - Installing distribute.............................................................................................................................................................................................done. 08:48:36 INFO - Installing pip...............done. 08:48:36 INFO - Return code: 0 08:48:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:48:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:48:36 INFO - Reading from file tmpfile_stdout 08:48:36 INFO - Output received: 08:48:36 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 08:48:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x215ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215f090>, '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 0x215fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2159f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', '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 08:48:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:48:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:48:36 INFO - Using env: {'DISPLAY': ':0', 08:48:36 INFO - 'HOME': '/home/cltbld', 08:48:36 INFO - 'LANG': 'en_US.UTF-8', 08:48:36 INFO - 'LANGUAGE': 'en_US:en', 08:48:36 INFO - 'LOGNAME': 'cltbld', 08:48:36 INFO - 'MAIL': '/var/mail/cltbld', 08:48:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:36 INFO - 'MOZ_NO_REMOTE': '1', 08:48:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:36 INFO - 'NO_EM_RESTART': '1', 08:48:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:36 INFO - 'PWD': '/builds/slave/test', 08:48:36 INFO - 'SHELL': '/bin/bash', 08:48:36 INFO - 'SHLVL': '1', 08:48:36 INFO - 'TERM': 'linux', 08:48:36 INFO - 'TMOUT': '86400', 08:48:36 INFO - 'USER': 'cltbld', 08:48:36 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', 08:48:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:36 INFO - '_': '/tools/buildbot/bin/python'} 08:48:38 INFO - Ignoring indexes: http://pypi.python.org/simple/ 08:48:38 INFO - Downloading/unpacking pip>=1.5 08:48:38 INFO - Running setup.py egg_info for package pip 08:48:38 INFO - warning: no files found matching 'pip/cacert.pem' 08:48:38 INFO - warning: no files found matching '*.html' under directory 'docs' 08:48:38 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 08:48:38 INFO - no previously-included directories found matching 'docs/_build/_sources' 08:48:38 INFO - Installing collected packages: pip 08:48:38 INFO - Found existing installation: pip 1.1 08:48:38 INFO - Uninstalling pip: 08:48:38 INFO - Successfully uninstalled pip 08:48:38 INFO - Running setup.py install for pip 08:48:38 INFO - warning: no files found matching 'pip/cacert.pem' 08:48:38 INFO - warning: no files found matching '*.html' under directory 'docs' 08:48:38 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 08:48:38 INFO - no previously-included directories found matching 'docs/_build/_sources' 08:48:38 INFO - Installing pip script to /builds/slave/test/build/venv/bin 08:48:38 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 08:48:38 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 08:48:38 INFO - Successfully installed pip 08:48:38 INFO - Cleaning up... 08:48:38 INFO - Return code: 0 08:48:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x215ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215f090>, '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 0x215fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2159f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', '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 08:48:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:48:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:48:38 INFO - Using env: {'DISPLAY': ':0', 08:48:38 INFO - 'HOME': '/home/cltbld', 08:48:38 INFO - 'LANG': 'en_US.UTF-8', 08:48:38 INFO - 'LANGUAGE': 'en_US:en', 08:48:38 INFO - 'LOGNAME': 'cltbld', 08:48:38 INFO - 'MAIL': '/var/mail/cltbld', 08:48:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:38 INFO - 'MOZ_NO_REMOTE': '1', 08:48:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:38 INFO - 'NO_EM_RESTART': '1', 08:48:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:38 INFO - 'PWD': '/builds/slave/test', 08:48:38 INFO - 'SHELL': '/bin/bash', 08:48:38 INFO - 'SHLVL': '1', 08:48:38 INFO - 'TERM': 'linux', 08:48:38 INFO - 'TMOUT': '86400', 08:48:38 INFO - 'USER': 'cltbld', 08:48:38 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', 08:48:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:38 INFO - '_': '/tools/buildbot/bin/python'} 08:48:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:38 INFO - Downloading/unpacking psutil>=3.1.1 08:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:48:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:48:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:48:39 INFO - Installing collected packages: psutil 08:48:39 INFO - Running setup.py install for psutil 08:48:39 INFO - building 'psutil._psutil_linux' extension 08:48:39 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:48:40 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 08:48:41 INFO - building 'psutil._psutil_posix' extension 08:48:41 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 08:48:41 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 08:48:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:48:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:48:41 INFO - Successfully installed psutil 08:48:41 INFO - Cleaning up... 08:48:41 INFO - Return code: 0 08:48:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:48:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:41 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 0x1df01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x215ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215f090>, '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 0x215fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2159f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', '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 08:48:41 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 08:48:41 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 08:48:41 INFO - Using env: {'DISPLAY': ':0', 08:48:41 INFO - 'HOME': '/home/cltbld', 08:48:41 INFO - 'LANG': 'en_US.UTF-8', 08:48:41 INFO - 'LANGUAGE': 'en_US:en', 08:48:41 INFO - 'LOGNAME': 'cltbld', 08:48:41 INFO - 'MAIL': '/var/mail/cltbld', 08:48:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:41 INFO - 'MOZ_NO_REMOTE': '1', 08:48:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:41 INFO - 'NO_EM_RESTART': '1', 08:48:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:41 INFO - 'PWD': '/builds/slave/test', 08:48:41 INFO - 'SHELL': '/bin/bash', 08:48:41 INFO - 'SHLVL': '1', 08:48:41 INFO - 'TERM': 'linux', 08:48:41 INFO - 'TMOUT': '86400', 08:48:41 INFO - 'USER': 'cltbld', 08:48:41 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', 08:48:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:41 INFO - '_': '/tools/buildbot/bin/python'} 08:48:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:48:41 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 08:48:41 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 08:48:41 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 08:48:41 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 08:48:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:48:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:48:42 INFO - Installing collected packages: mozsystemmonitor 08:48:42 INFO - Running setup.py install for mozsystemmonitor 08:48:42 INFO - Successfully installed mozsystemmonitor 08:48:42 INFO - Cleaning up... 08:48:42 INFO - Return code: 0 08:48:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x215ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215f090>, '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 0x215fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2159f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', '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 08:48:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:48:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:48:42 INFO - Using env: {'DISPLAY': ':0', 08:48:42 INFO - 'HOME': '/home/cltbld', 08:48:42 INFO - 'LANG': 'en_US.UTF-8', 08:48:42 INFO - 'LANGUAGE': 'en_US:en', 08:48:42 INFO - 'LOGNAME': 'cltbld', 08:48:42 INFO - 'MAIL': '/var/mail/cltbld', 08:48:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:42 INFO - 'MOZ_NO_REMOTE': '1', 08:48:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:42 INFO - 'NO_EM_RESTART': '1', 08:48:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:42 INFO - 'PWD': '/builds/slave/test', 08:48:42 INFO - 'SHELL': '/bin/bash', 08:48:42 INFO - 'SHLVL': '1', 08:48:42 INFO - 'TERM': 'linux', 08:48:42 INFO - 'TMOUT': '86400', 08:48:42 INFO - 'USER': 'cltbld', 08:48:42 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', 08:48:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:42 INFO - '_': '/tools/buildbot/bin/python'} 08:48:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:43 INFO - Downloading/unpacking jsonschema==2.5.1 08:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:48:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:48:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:44 INFO - Downloading functools32-3.2.3-2.tar.gz 08:48:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:48:44 INFO - warning: no files found matching '*.txt' 08:48:44 INFO - no previously-included directories found matching 'build' 08:48:44 INFO - no previously-included directories found matching 'dist' 08:48:44 INFO - no previously-included directories found matching '.git*' 08:48:44 INFO - Installing collected packages: jsonschema, functools32 08:48:44 INFO - Running setup.py install for functools32 08:48:44 INFO - warning: no files found matching '*.txt' 08:48:44 INFO - no previously-included directories found matching 'build' 08:48:44 INFO - no previously-included directories found matching 'dist' 08:48:44 INFO - no previously-included directories found matching '.git*' 08:48:44 INFO - Successfully installed jsonschema functools32 08:48:44 INFO - Cleaning up... 08:48:44 INFO - Return code: 0 08:48:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:48:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x215ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215f090>, '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 0x215fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2159f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', '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 08:48:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:48:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:48:44 INFO - Using env: {'DISPLAY': ':0', 08:48:44 INFO - 'HOME': '/home/cltbld', 08:48:44 INFO - 'LANG': 'en_US.UTF-8', 08:48:44 INFO - 'LANGUAGE': 'en_US:en', 08:48:44 INFO - 'LOGNAME': 'cltbld', 08:48:44 INFO - 'MAIL': '/var/mail/cltbld', 08:48:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:44 INFO - 'MOZ_NO_REMOTE': '1', 08:48:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:44 INFO - 'NO_EM_RESTART': '1', 08:48:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:44 INFO - 'PWD': '/builds/slave/test', 08:48:44 INFO - 'SHELL': '/bin/bash', 08:48:44 INFO - 'SHLVL': '1', 08:48:44 INFO - 'TERM': 'linux', 08:48:44 INFO - 'TMOUT': '86400', 08:48:44 INFO - 'USER': 'cltbld', 08:48:44 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', 08:48:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:44 INFO - '_': '/tools/buildbot/bin/python'} 08:48:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:48:44 INFO - Cleaning up... 08:48:44 INFO - Return code: 0 08:48:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:48:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x215ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215f090>, '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 0x215fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2159f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', '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 08:48:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:48:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:48:44 INFO - Using env: {'DISPLAY': ':0', 08:48:44 INFO - 'HOME': '/home/cltbld', 08:48:44 INFO - 'LANG': 'en_US.UTF-8', 08:48:44 INFO - 'LANGUAGE': 'en_US:en', 08:48:44 INFO - 'LOGNAME': 'cltbld', 08:48:44 INFO - 'MAIL': '/var/mail/cltbld', 08:48:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:44 INFO - 'MOZ_NO_REMOTE': '1', 08:48:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:44 INFO - 'NO_EM_RESTART': '1', 08:48:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:44 INFO - 'PWD': '/builds/slave/test', 08:48:44 INFO - 'SHELL': '/bin/bash', 08:48:44 INFO - 'SHLVL': '1', 08:48:44 INFO - 'TERM': 'linux', 08:48:44 INFO - 'TMOUT': '86400', 08:48:44 INFO - 'USER': 'cltbld', 08:48:44 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', 08:48:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:44 INFO - '_': '/tools/buildbot/bin/python'} 08:48:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:44 INFO - Downloading/unpacking blobuploader==1.2.4 08:48:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:46 INFO - Downloading blobuploader-1.2.4.tar.gz 08:48:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:48:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:48:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:46 INFO - Downloading docopt-0.6.1.tar.gz 08:48:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:48:46 INFO - Installing collected packages: blobuploader, requests, docopt 08:48:46 INFO - Running setup.py install for blobuploader 08:48:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:48:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:48:46 INFO - Running setup.py install for requests 08:48:47 INFO - Running setup.py install for docopt 08:48:47 INFO - Successfully installed blobuploader requests docopt 08:48:47 INFO - Cleaning up... 08:48:47 INFO - Return code: 0 08:48:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:48: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')]} 08:48:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48: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')]} 08:48:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x215ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215f090>, '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 0x215fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2159f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', '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 08:48:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:48:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:48:47 INFO - Using env: {'DISPLAY': ':0', 08:48:47 INFO - 'HOME': '/home/cltbld', 08:48:47 INFO - 'LANG': 'en_US.UTF-8', 08:48:47 INFO - 'LANGUAGE': 'en_US:en', 08:48:47 INFO - 'LOGNAME': 'cltbld', 08:48:47 INFO - 'MAIL': '/var/mail/cltbld', 08:48:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:47 INFO - 'MOZ_NO_REMOTE': '1', 08:48:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:47 INFO - 'NO_EM_RESTART': '1', 08:48:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:47 INFO - 'PWD': '/builds/slave/test', 08:48:47 INFO - 'SHELL': '/bin/bash', 08:48:47 INFO - 'SHLVL': '1', 08:48:47 INFO - 'TERM': 'linux', 08:48:47 INFO - 'TMOUT': '86400', 08:48:47 INFO - 'USER': 'cltbld', 08:48:47 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', 08:48:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:47 INFO - '_': '/tools/buildbot/bin/python'} 08:48:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:48:47 INFO - Running setup.py (path:/tmp/pip-_lBsot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:48:47 INFO - Running setup.py (path:/tmp/pip-3zk5Pl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:48:47 INFO - Running setup.py (path:/tmp/pip-6EmBrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:48:47 INFO - Running setup.py (path:/tmp/pip-1jFiMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:48:47 INFO - Running setup.py (path:/tmp/pip-1OxCLp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:48:47 INFO - Running setup.py (path:/tmp/pip-m6VMKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:48:47 INFO - Running setup.py (path:/tmp/pip-K2MMBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:48:47 INFO - Running setup.py (path:/tmp/pip-J_xVYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:48:48 INFO - Running setup.py (path:/tmp/pip-ud2WEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:48:48 INFO - Running setup.py (path:/tmp/pip-Br28ci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:48:48 INFO - Running setup.py (path:/tmp/pip-krk21p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:48:48 INFO - Running setup.py (path:/tmp/pip-9QPl7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:48:48 INFO - Running setup.py (path:/tmp/pip-auEA8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:48:48 INFO - Running setup.py (path:/tmp/pip-ohrBax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:48:48 INFO - Running setup.py (path:/tmp/pip-FbeaQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:48:48 INFO - Running setup.py (path:/tmp/pip-h_l7jl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:48:48 INFO - Running setup.py (path:/tmp/pip-qV1IDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:48:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:48:48 INFO - Running setup.py install for manifestparser 08:48:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:48:48 INFO - Running setup.py install for mozcrash 08:48:48 INFO - Running setup.py install for mozdebug 08:48:48 INFO - Running setup.py install for mozdevice 08:48:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:48:49 INFO - Running setup.py install for mozfile 08:48:49 INFO - Running setup.py install for mozhttpd 08:48:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:48:49 INFO - Running setup.py install for mozinfo 08:48:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:48:49 INFO - Running setup.py install for mozInstall 08:48:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:48:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:48:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:48:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:48:49 INFO - Running setup.py install for mozleak 08:48:49 INFO - Running setup.py install for mozlog 08:48:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:48:49 INFO - Running setup.py install for moznetwork 08:48:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:48:49 INFO - Running setup.py install for mozprocess 08:48:49 INFO - Running setup.py install for mozprofile 08:48:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:48:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:48:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:48:49 INFO - Running setup.py install for mozrunner 08:48:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:48:50 INFO - Running setup.py install for mozscreenshot 08:48:50 INFO - Running setup.py install for moztest 08:48:50 INFO - Running setup.py install for mozversion 08:48:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:48:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:48:50 INFO - Cleaning up... 08:48:50 INFO - Return code: 0 08:48:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x215ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215f090>, '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 0x215fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2159f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', '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 08:48:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:48:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:48:50 INFO - Using env: {'DISPLAY': ':0', 08:48:50 INFO - 'HOME': '/home/cltbld', 08:48:50 INFO - 'LANG': 'en_US.UTF-8', 08:48:50 INFO - 'LANGUAGE': 'en_US:en', 08:48:50 INFO - 'LOGNAME': 'cltbld', 08:48:50 INFO - 'MAIL': '/var/mail/cltbld', 08:48:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:50 INFO - 'MOZ_NO_REMOTE': '1', 08:48:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:50 INFO - 'NO_EM_RESTART': '1', 08:48:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:50 INFO - 'PWD': '/builds/slave/test', 08:48:50 INFO - 'SHELL': '/bin/bash', 08:48:50 INFO - 'SHLVL': '1', 08:48:50 INFO - 'TERM': 'linux', 08:48:50 INFO - 'TMOUT': '86400', 08:48:50 INFO - 'USER': 'cltbld', 08:48:50 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', 08:48:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:50 INFO - '_': '/tools/buildbot/bin/python'} 08:48:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:48:50 INFO - Running setup.py (path:/tmp/pip-uoftkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:48:50 INFO - Running setup.py (path:/tmp/pip-flhPQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:48:50 INFO - Running setup.py (path:/tmp/pip-S1R4lU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:48:50 INFO - Running setup.py (path:/tmp/pip-TGB1Nb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:48:50 INFO - Running setup.py (path:/tmp/pip-K56RkG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:48:51 INFO - Running setup.py (path:/tmp/pip-AbE97A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:48:51 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)) 08:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:48:51 INFO - Running setup.py (path:/tmp/pip-ivcfch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:48:51 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)) 08:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:48:51 INFO - Running setup.py (path:/tmp/pip-KTtcoq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:48:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:48:51 INFO - Running setup.py (path:/tmp/pip-AIXqVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:48:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:48:51 INFO - Running setup.py (path:/tmp/pip-trv18m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:48:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:48:51 INFO - Running setup.py (path:/tmp/pip-_rU_Zt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:48:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:48:51 INFO - Running setup.py (path:/tmp/pip-rCLniK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:48:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:48:51 INFO - Running setup.py (path:/tmp/pip-35veqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:48:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:48:51 INFO - Running setup.py (path:/tmp/pip-Ub7yMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:48:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:48:51 INFO - Running setup.py (path:/tmp/pip-B0s4dE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:48:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:48:51 INFO - Running setup.py (path:/tmp/pip-dbdzt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:48:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:48:51 INFO - Running setup.py (path:/tmp/pip-UTPwXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:48:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:48:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:48:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:48:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:48:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:48:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:48:51 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:48:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:52 INFO - Downloading blessings-1.6.tar.gz 08:48:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:48:53 INFO - Installing collected packages: blessings 08:48:53 INFO - Running setup.py install for blessings 08:48:53 INFO - Successfully installed blessings 08:48:53 INFO - Cleaning up... 08:48:53 INFO - Return code: 0 08:48:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:48:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:48:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:48:53 INFO - Reading from file tmpfile_stdout 08:48:53 INFO - Current package versions: 08:48:53 INFO - argparse == 1.2.1 08:48:53 INFO - blessings == 1.6 08:48:53 INFO - blobuploader == 1.2.4 08:48:53 INFO - docopt == 0.6.1 08:48:53 INFO - functools32 == 3.2.3-2 08:48:53 INFO - jsonschema == 2.5.1 08:48:53 INFO - manifestparser == 1.1 08:48:53 INFO - mozInstall == 1.13 08:48:53 INFO - mozcrash == 1.0 08:48:53 INFO - mozdebug == 0.1 08:48:53 INFO - mozdevice == 0.51 08:48:53 INFO - mozfile == 1.2 08:48:53 INFO - mozhttpd == 0.7 08:48:53 INFO - mozinfo == 0.10 08:48:53 INFO - mozleak == 0.1 08:48:53 INFO - mozlog == 3.5 08:48:53 INFO - moznetwork == 0.27 08:48:53 INFO - mozprocess == 0.25 08:48:53 INFO - mozprofile == 0.28 08:48:53 INFO - mozrunner == 6.13 08:48:53 INFO - mozscreenshot == 0.1 08:48:53 INFO - mozsystemmonitor == 0.3 08:48:53 INFO - moztest == 0.8 08:48:53 INFO - mozversion == 1.4 08:48:53 INFO - psutil == 3.1.1 08:48:53 INFO - requests == 1.2.3 08:48:53 INFO - wsgiref == 0.1.2 08:48:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:48:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x215ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215f090>, '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 0x215fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2159f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', '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 08:48:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:48:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:48:53 INFO - Using env: {'DISPLAY': ':0', 08:48:53 INFO - 'HOME': '/home/cltbld', 08:48:53 INFO - 'LANG': 'en_US.UTF-8', 08:48:53 INFO - 'LANGUAGE': 'en_US:en', 08:48:53 INFO - 'LOGNAME': 'cltbld', 08:48:53 INFO - 'MAIL': '/var/mail/cltbld', 08:48:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:53 INFO - 'MOZ_NO_REMOTE': '1', 08:48:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:53 INFO - 'NO_EM_RESTART': '1', 08:48:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:53 INFO - 'PWD': '/builds/slave/test', 08:48:53 INFO - 'SHELL': '/bin/bash', 08:48:53 INFO - 'SHLVL': '1', 08:48:53 INFO - 'TERM': 'linux', 08:48:53 INFO - 'TMOUT': '86400', 08:48:53 INFO - 'USER': 'cltbld', 08:48:53 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', 08:48:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:53 INFO - '_': '/tools/buildbot/bin/python'} 08:48:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:48:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:48:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:48:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:48:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:48:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:48:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:48:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:48:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:48:53 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 08:48:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:48:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:48:54 INFO - Installing collected packages: simplejson 08:48:54 INFO - Running setup.py install for simplejson 08:48:55 INFO - building 'simplejson._speedups' extension 08:48:55 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 08:48:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 08:48:55 INFO - Successfully installed simplejson 08:48:55 INFO - Cleaning up... 08:48:55 INFO - Return code: 0 08:48:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:48: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')]} 08:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48: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')]} 08:48:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x215ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215f090>, '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 0x215fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2159f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', '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 08:48:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:48:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:48:55 INFO - Using env: {'DISPLAY': ':0', 08:48:55 INFO - 'HOME': '/home/cltbld', 08:48:55 INFO - 'LANG': 'en_US.UTF-8', 08:48:55 INFO - 'LANGUAGE': 'en_US:en', 08:48:55 INFO - 'LOGNAME': 'cltbld', 08:48:55 INFO - 'MAIL': '/var/mail/cltbld', 08:48:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:55 INFO - 'MOZ_NO_REMOTE': '1', 08:48:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:55 INFO - 'NO_EM_RESTART': '1', 08:48:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:56 INFO - 'PWD': '/builds/slave/test', 08:48:56 INFO - 'SHELL': '/bin/bash', 08:48:56 INFO - 'SHLVL': '1', 08:48:56 INFO - 'TERM': 'linux', 08:48:56 INFO - 'TMOUT': '86400', 08:48:56 INFO - 'USER': 'cltbld', 08:48:56 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', 08:48:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:56 INFO - '_': '/tools/buildbot/bin/python'} 08:48:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:48:56 INFO - Cleaning up... 08:48:56 INFO - Return code: 0 08:48:56 INFO - Running post-action listener: _resource_record_post_action 08:48:56 INFO - Running post-action listener: _start_resource_monitoring 08:48:56 INFO - Starting resource monitoring. 08:48:56 INFO - [mozharness: 2017-10-02 15:48:56.291008Z] Finished create-virtualenv step (success) 08:48:56 INFO - [mozharness: 2017-10-02 15:48:56.292002Z] Running install step. 08:48:56 INFO - Running pre-action listener: _resource_record_pre_action 08:48:56 INFO - Running main action method: install 08:48:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:48:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:48:56 INFO - Reading from file tmpfile_stdout 08:48:56 INFO - Detecting whether we're running mozinstall >=1.0... 08:48:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:48:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:48:56 INFO - Reading from file tmpfile_stdout 08:48:56 INFO - Output received: 08:48:56 INFO - Usage: mozinstall [options] installer 08:48:56 INFO - Options: 08:48:56 INFO - -h, --help show this help message and exit 08:48:56 INFO - -d DEST, --destination=DEST 08:48:56 INFO - Directory to install application into. [default: 08:48:56 INFO - "/builds/slave/test"] 08:48:56 INFO - --app=APP Application being installed. [default: firefox] 08:48:56 INFO - mkdir: /builds/slave/test/build/application 08:48:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:48:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:49:11 INFO - Reading from file tmpfile_stdout 08:49:11 INFO - Output received: 08:49:11 INFO - /builds/slave/test/build/application/firefox/firefox 08:49:11 INFO - Running post-action listener: _resource_record_post_action 08:49:11 INFO - [mozharness: 2017-10-02 15:49:11.227364Z] Finished install step (success) 08:49:11 INFO - [mozharness: 2017-10-02 15:49:11.227705Z] Running setup-mitmproxy step. 08:49:11 INFO - Running pre-action listener: _resource_record_pre_action 08:49:11 INFO - Running main action method: setup_mitmproxy 08:49:11 INFO - Skipping: mitmproxy is not required 08:49:11 INFO - Running post-action listener: _resource_record_post_action 08:49:11 INFO - [mozharness: 2017-10-02 15:49:11.229301Z] Finished setup-mitmproxy step (success) 08:49:11 INFO - [mozharness: 2017-10-02 15:49:11.229753Z] Running run-tests step. 08:49:11 INFO - Running pre-action listener: _resource_record_pre_action 08:49:11 INFO - Running pre-action listener: _set_gcov_prefix 08:49:11 INFO - Running main action method: run_tests 08:49:11 WARNING - Try message not found. 08:49:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:49:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:49:11 INFO - Python 2.7.3 08:49:11 INFO - Return code: 0 08:49:11 INFO - grabbing minidump binary from tooltool 08:49:11 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')]} 08:49:11 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 0x215f090>, '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 0x215fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2159f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:49:11 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 08:49:11 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 08:49:11 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 08:49:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:49:11 INFO - Return code: 0 08:49:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:49:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:49:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:49:11 INFO - ENV: RUST_BACKTRACE is now full 08:49:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:49:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:49:11 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/d3dkFuYKSOCZAIW9aj6NGQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 08:49:11 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/d3dkFuYKSOCZAIW9aj6NGQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-069 --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 08:49:11 INFO - Using env: {'DISPLAY': ':0', 08:49:11 INFO - 'HOME': '/home/cltbld', 08:49:11 INFO - 'LANG': 'en_US.UTF-8', 08:49:11 INFO - 'LANGUAGE': 'en_US:en', 08:49:11 INFO - 'LOGNAME': 'cltbld', 08:49:11 INFO - 'MAIL': '/var/mail/cltbld', 08:49:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:49:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:11 INFO - 'MOZ_NO_REMOTE': '1', 08:49:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:49:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:11 INFO - 'NO_EM_RESTART': '1', 08:49:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:11 INFO - 'PWD': '/builds/slave/test', 08:49:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:49:11 INFO - 'RUST_BACKTRACE': 'full', 08:49:11 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:49:11 INFO - 'SHELL': '/bin/bash', 08:49:11 INFO - 'SHLVL': '1', 08:49:11 INFO - 'TERM': 'linux', 08:49:11 INFO - 'TMOUT': '86400', 08:49:11 INFO - 'USER': 'cltbld', 08:49:11 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', 08:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:11 INFO - '_': '/tools/buildbot/bin/python'} 08:49:11 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/d3dkFuYKSOCZAIW9aj6NGQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 08:49:11 INFO - mozversion application_buildid: 20171002142801 08:49:11 INFO - mozversion application_changeset: 35bae846b62970fc2faa9c0979c2544eff3c04da 08:49:11 INFO - mozversion application_display_name: Firefox 08:49:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:49:11 INFO - mozversion application_name: Firefox 08:49:11 INFO - mozversion application_remotingname: firefox 08:49:11 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:49:11 INFO - mozversion application_vendor: Mozilla 08:49:11 INFO - mozversion application_version: 57.0 08:49:11 INFO - mozversion platform_buildid: 20171002142801 08:49:11 INFO - mozversion platform_changeset: 35bae846b62970fc2faa9c0979c2544eff3c04da 08:49:11 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:49:11 INFO - mozversion platform_version: 57.0 08:49:11 INFO - using testdate: 1506959351 08:49:11 INFO - actual date: 1506959351 08:49:11 INFO - starting webserver on 'localhost:55747' 08:49:11 INFO - SUITE-START | Running 2 tests 08:49:11 INFO - TEST-START | dromaeo_css 08:49:11 INFO - Initialising browser for dromaeo_css test... 08:49:11 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55747/getInfo.html -profile /tmp/tmpvNYmQ8/profile 08:49:11 INFO - TEST-INFO | started process 3606 (/builds/slave/test/build/application/firefox/firefox http://localhost:55747/getInfo.html) 08:49:19 INFO - TEST-INFO | 3606: exit 0 08:49:19 INFO - Browser initialized. 08:49:19 INFO - Running cycle 1/1 for dromaeo_css test... 08:49:19 INFO - Using env: {'DISPLAY': ':0', 08:49:19 INFO - 'HOME': '/home/cltbld', 08:49:19 INFO - 'JSGC_DISABLE_POISONING': '1', 08:49:19 INFO - 'LANG': 'en_US.UTF-8', 08:49:19 INFO - 'LANGUAGE': 'en_US:en', 08:49:19 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 08:49:19 INFO - 'LOGNAME': 'cltbld', 08:49:19 INFO - 'MAIL': '/var/mail/cltbld', 08:49:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:49:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:49:19 INFO - 'MOZ_CRASHREPORTER': '1', 08:49:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:19 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 08:49:19 INFO - 'MOZ_NO_REMOTE': '1', 08:49:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:49:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:19 INFO - 'NO_EM_RESTART': '1', 08:49:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:19 INFO - 'PWD': '/builds/slave/test', 08:49:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:49:19 INFO - 'RUST_BACKTRACE': 'full', 08:49:19 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:49:19 INFO - 'SHELL': '/bin/bash', 08:49:19 INFO - 'SHLVL': '1', 08:49:19 INFO - 'TERM': 'linux', 08:49:19 INFO - 'TMOUT': '86400', 08:49:19 INFO - 'USER': 'cltbld', 08:49:19 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', 08:49:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:19 INFO - '_': '/tools/buildbot/bin/python'} 08:49:19 INFO - TEST-INFO | started process 3797 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvNYmQ8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 08:49:20 INFO - PID 3797 | 08:49:20 INFO - PID 3797 | (/builds/slave/test/build/application/firefox/firefox:3846): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 08:49:20 INFO - PID 3797 | 08:49:30 INFO - PID 3797 | RSS: Main: 141418496 08:49:30 INFO - PID 3797 | 08:52:28 INFO - PID 3797 | Cycle 1(1): loaded http://localhost:55747/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55747/tests/dromaeo/cssquery-ext.html) 08:52:29 INFO - PID 3797 | RSS: Main: 145137664 08:52:29 INFO - PID 3797 | 08:55:30 INFO - PID 3797 | Cycle 1(1): loaded http://localhost:55747/tests/dromaeo/cssquery-ext.html (next: http://localhost:55747/tests/dromaeo/cssquery-jquery.html) 08:55:31 INFO - PID 3797 | RSS: Main: 144302080 08:55:31 INFO - PID 3797 | 08:58:32 INFO - PID 3797 | Cycle 1(1): loaded http://localhost:55747/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55747/tests/dromaeo/cssquery-mootools.html) 08:58:33 INFO - PID 3797 | RSS: Main: 145149952 08:58:33 INFO - PID 3797 | 09:01:34 INFO - PID 3797 | Cycle 1(1): loaded http://localhost:55747/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55747/tests/dromaeo/cssquery-prototype.html) 09:01:35 INFO - PID 3797 | RSS: Main: 144556032 09:01:35 INFO - PID 3797 | 09:04:38 INFO - PID 3797 | Cycle 1(1): loaded http://localhost:55747/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55747/tests/dromaeo/cssquery-yui.html) 09:04:39 INFO - PID 3797 | RSS: Main: 145793024 09:04:39 INFO - PID 3797 | 09:07:40 INFO - PID 3797 | Cycle 1(1): loaded http://localhost:55747/tests/dromaeo/cssquery-yui.html (next: http://localhost:55747/tests/dromaeo/cssquery-dojo.html) 09:07:40 INFO - PID 3797 | RSS: Main: 148369408 09:07:40 INFO - PID 3797 | 09:07:40 INFO - PID 3797 | __start_tp_report 09:07:40 INFO - PID 3797 | _x_x_mozilla_page_load 09:07:40 INFO - PID 3797 | _x_x_mozilla_page_load_details 09:07:40 INFO - PID 3797 | |i|pagename|runs| 09:07:40 INFO - PID 3797 | |0;dojo.html;3959.742616729914;3979.6617287530557;3982.283189025975;3993.7404068735536;4009.7393669411467;6528.706208220629;6529.869402611945;6563.376479234471;6595.340465953403;6625.337466253372;6516.478681705464;6527.6328468647525;6531.542792004561;6608.900866487006;6629.834254143649;5860.208871802316;5940.079287710401;5944.346121926606;5951.642901425928;5966.940330596682;5872.17789509469;5928.940710592904;5953.315368732587;6006.819795406123;6018.578699491038;5838.124281357788;5961.701914904237;5973.85065373367;6023.126646636257;6043.305019922699;5763.423657634245;5883.382244864313;5921.674307913063;5935.080062590284;5937.6140550864275;5758;5819.505342045931;5821.359650438449;5919.260092488439;5933.436323549256;6289.8742025159245;6368.8089357318895;6376.7130479128555;6395.232572091379;6435.903461448082;6876.69054892526;6940.687669054904;6964.4428445724225;6966.3730264276455;6972.616506092163;6894.517383783137;6954.652267386661;6965.582065076061;7017.157941047107;7021.719131234744;6475.061116138134;6578.177727784027;6585.407313341774;6625.569337993015;6720.4287635550545;7331.596762178131;7333.339999399973;7388.445866559922;7388.926110738824;7393.963030184815;6991.965040174869;6994.33553812387;7000.50996430245;7081.82295442618;7088.539244949062;7080.185778635523;7135.785926422215;7148.678309476086;7161.39128174111;7212.350888420067;7000.719971201095;7015.964920175366;7024.754133605299;7028.4025857801635;7030.226675065841;6829.419499342612;6934.861302773917;6935.098437203233;7016.193137789118;7024.121984751906;5127.282180494733;5169.327987361619;5195.428502864682;5208.7916483341;5211.7915283389;4716.976415117902;4765.094632019975;4825.782839772219;4828.03439312139;4838.830640927551;6594.934050659528;6649.168853893263;6712.228093769181;6722.630255336004;6729.327067293231;7034.120734908137;7047.154341479055;7116.92883071173;7139.643017849086;7142.357187853014;6547.247066587308;6614.966925165343;6627.635480048547;6629.337066293332;6648.235452922879;5782.681952492653;5882.088276317178;5903.468687818117;5911.0542313230535;5920.200772895604;7268.055152830098;7351.22812104732;7430.814229644194;7452.06849143857;7460.5150665207675;6986.650667466504;7036.824079398056;7036.824079398056;7039.401650859734;7049.118860142482;7279.23332789651;7367.336939675455;7377.557346559223;7387.150477694919;7388.298111679382;6960.965195173992;7019.964900175466;7039.894401583878;7041.225465198721;7046;7151.53515021522;7157.85684286301;7174.964125179341;7213.747518836815;7218.242084581043;6792.932070679428;6847.863042739217;6860.931390686029;6880.105586273752;6931.445484361138;6588.242352129567;6817.113775209191;6817.693203805741;6894.620795856276;6914.377706006282;6750.493712971449;6764.053032575542;6800.455963523005;6813.591184528944;6817.284185160487;6838.213605435439;6880.69036893351;6960.617166055713;6971.3028697129885;6974.6861391238535;6646.401823835878;6648;6725.361090696376;6734.09089772894;6741.629210393476 09:07:40 INFO - PID 3797 | |1;ext.html;981.8527220916861;1012.8379459286512;1013.8732658417698;1017.3183966742278;1017.801528701903;47523.76238118809;48393;48421.27368089482;48439.03121937563;48462;45551;45714.77142614282;45886.08227835441;45977.770111149395;46005.079898402095;8908.821823563525;9080.59137338818;9098.044705305914;9105.544722763869;9109.27125829932;8918.687845925393;8933.330000249975;8937.061608531109;9020.14308640678;9027.322950778685;9022.684206052789;9023.864642030341;9037.096290370977;9040.321975851804;9049.683261085831;8969.999999999967;8974.371793974391;8975.147361000694;9035.232005279559;9043.683471078513;8837.425567338167;8896.555172241362;8897.199252067281;8907.955460222658;8921.15249051339;42502.149957001;42928.141437171085;43103.78448107771;43276.78361608172;43353.13293734139;3800.695944324475;3856.3251430999744;3868.825902834379;3870.5161854768153;3874.5156855393075;36017.45973810395;36031.63968360309;36086.458703119475;36351.81824090889;36393.45409818855;5486.561075114021;5658.122990936411;5709.600327977043;5729.914051289234;5736.598438109334;240697;241017;241200;241791;241818;48939.26591101136;50014.49985500171;55881.1617825733;56123.158152627744;56542;60020.39979600148;60164.09753853696;60189.69905150446;60210.096848547306;60253.17974562025;18389.724154137697;18510.81489185118;18555.16501757424;18565.16456759449;18591.34930277434;15265.771013434807;15281.770773438408;15296.847031529765;15319.157446340558;15455.613609659624;16000.679986400206;16089.436869709503;16097.597560060858;16129.193540322936;16141.192940352936;6264.467520260738;6367.649779262092;6383.499877528777;6386.521010924199;6405.391487808651;1132.6432173865205;1142.5715356741223;1147.701597584634;1164.057113737875;1166.0438440478727;26901.865490672422;26965.921363145648;27128.593071103554;27186.86406567994;27506.862465687944;1247.7255003899127;1260.3005332040805;1268.460904115747;1269.3716610277972;1284.743051389707;14173.433062677377;14268.013629505223;14308.856911430961;14425.639359016108;14550.199739014244;286773;286793;287604;287946;289327;61050;61153.38846611566;61441;61452;61474;1579.8104227492774;1629.5926018495613;1630.4537979776908;1634.7333459670251;1653.264297387675;25950.87024564865;25987.870060649577;26050.609240861024;26052.73947260579;26200.606990895532;43463.348049778644;43463.56536434595;43700.12599748051;43805.56194438015;43918.34122488229;244020;244093;244334;245327;248076;36516;37285.25429491449;39462.210755785294;40163.397549036206;40186.79906600448;51674;51692;51756.22365664593;52015.479845201065;52369.738151309;37577.4363384544;37912.81043594764;37915.24169516649;38021.80989095037;38075.80962095172;37801.432978504796;37916.43125353067;38125.2374952505;38274;38301.04247393726;42346.15307693767;43137.7843110795;43319.783401082794;43324.56675433331;43518.34722479102;35901;36007;36123.27753444969;36327.63672363242;36412.27175456529;26632.467350653267;26969.73030269672;27060.323491912073;27259.228150170176;27281.59077613877 09:07:40 INFO - PID 3797 | |2;jquery.html;627.3945642455033;627.9215098112736;632.092946621598;638.4764493115646;639.1020616034472;24678.87660561697;24943.37641558962;25401.36496587584;25637.87181064097;26683.332916677093;26542.33644158897;27845.721542784566;27893.442131157364;27987.58018629717;28015.85992070042;6692.096566963459;6798.932010679904;6815.454763618924;6820.488463365267;6822.385985261326;6503.577267477599;6722.529422940396;6732.0575119483065;6753.15585551806;6783.253842077367;6599.076129341896;6662.633555154464;6714.6306953117555;6721.663916804165;6748.797536073926;6728.764493242737;6731.225909020452;6743.662816859187;6759.797206083826;6766.932330676704;6276.215473065867;6328.208973878265;6329.303776584572;6331.620102793847;6335.081413195107;31230.21924451884;31926;31999.52000719991;32268.193295167574;32490.700371985327;2361.9527609447887;2395.3293077938206;2398.712154541449;2399.6040653292184;2401.6637670726113;20470.38588842337;21054.473638159016;21112.894435527724;21125.894370528204;21135.3659390217;2605.7654811066905;2650.2314328844614;2686.422419179866;2711.1053352393665;2717.2255907066587;138967;145415.2729236347;147948.2602586991;154956;155192.2240388791;16283.674326513641;17050.914745426195;17311.220995055253;17363.47909562715;17848.375306864196;26759;26789.330266743487;26815.927362905655;26897.596536051973;26934.865325673247;11333.43332833355;11595.710107247387;11755.235909665844;11767.646970590897;11849.703757405961;10548.208884333706;10615.575376984834;10618.415987120628;10667.253292269466;10681.145508359312;10100.848487272697;10311.74220644475;10343.689689309333;10462.26764126519;10492.947535262274;2882.437924604682;2904.433635441111;2932.9560056599166;2964.614600102016;3035.7874948753374;1048.4286064095086;1067.9839802402971;1071.7250498332307;1076.6285631457135;1077.8113830079706;22177.11291548352;22396.776032239795;22790.430239244153;22817;22915.42711432194;1128.5372997071327;1144.9026832719146;1148.0930065248526;1150.8734039255676;1155.0413157079604;12335;12468.81296780549;12555.309457979716;12641.747165056648;12686.492540298284;650222;650604;667729;678676;692423;131165;131754;132850;134312.3284383572;135799;1249.6563445052502;1262.6527704881048;1298.8441387033618;1303.4069498378406;1310.2989900403238;19266.903665481583;19938.501537461678;19976.3008294709;20072.59854802925;20299;62819.05771413524;64296;65212.34787652063;65606;66189;80239.59880200795;80921;81476;82515;84308;15986.760198597263;16011.039337639313;16341.101239431946;16409.67180656404;16477.01137931728;25036.874815626536;25125.623115653645;25189.748102519472;25339.113131040325;25529.23412297634;20854.687179692013;20907.163713451293;20966.266180683604;21125;21179.47051323729;18074.277028918696;18077.638447231246;18146.546336341697;18269.634607308046;18597.34909278169;21216.78783212148;21293.254736084164;21445;21611;21630.35108946734;16137.67724645524;16176.67646647084;16207.837921620632;16226.350945962029;16265.105419202058;18178.72731909049;18236.908815455838;18636.068196590386;20810.27164049251;20997.265095721577 09:07:40 INFO - PID 3797 | |3;mootools.html;1443.9133651980883;1466.8093147890772;1480.518831379802;1486.710091532151;1493.4623535527212;17298.394556190535;17874.731879021794;17883;17883.642327153448;17887.821121788777;17552.473425797245;17660.558486037855;17668.204930778113;17719.1140442978;17733.733993990067;2948.0713575223735;2965.1401093682807;3004.8347340896244;3015.909522714311;3015.9396812063746;2982.3438843454405;2983.0603359941656;3001.819890806548;3007.9398412031746;3013.8643761030808;2888.335682792959;2912.0535825856578;2981.8509074546296;2990.491616425213;2996.2060054085687;3006.759459243255;3021.42592907348;3030.5605687175457;3032.545118232261;3032.6815684353046;2889.3354528458362;2907.912762617125;2929.8974535891352;2932.4868148074;2935.6036935013926;17040.914795426066;17291.394801181897;17341;17345.566360840952;17360.13199340028;9129.634814607407;9434.764130896714;9488.667896623585;9500.857487137699;9542.42745435276;5314.256004159421;5567.3040869891265;5688.402717714622;5695.886082278331;5717.628354157008;2031.4616626593966;2054.7123402723632;2078.272604588382;2118.9682154767693;2122.09810830398;31574.368512629848;31931.201719956956;32026.039218823473;32167.678323216936;32187.35625287481;7575.583342916082;7577.772666820004;7596.506227095221;7619.504732192389;7747.496412733154;20029.699554506693;20045;20096;20109.597808043756;20133.19467221324;8536.487810731376;8699.565021749015;8724.738257852274;8752.956235218911;8756.56217189138;7731.536107833548;7874.251946065115;8314.584270786436;8365.95817020911;8526.531040792815;5869.618474799125;5876.794312199053;6017.428344307284;6147.385261473906;6224.533160013018;1596.4811436283255;1637.2795969773263;1643.9424620138477;1709.495698768866;1730.7490413890168;1020.1125021231576;1026.10728666061;1032.6282538286212;1037.7042542875245;1052.5947510208625;9457.243420526342;9523.095305945924;9550.570224339921;9601.135897769234;10496.68509944703;1045.811753884293;1058.1746237934424;1120.0871289898707;1137.3062431916524;1137.7951968645557;5789.080029917726;6138.1099740537375;6153.661548614779;6167.198264225622;6224.315325314212;26217;26528.734712652626;26784.59823102616;26909.86545067262;26941;26642.733572664027;26772.062977795682;26875.328116797238;26995.920163193252;27053.1884043479;12614.675459076849;13176;13252.20486770797;13280.269585172908;13359.59921202346;9832.31173817826;9983;9996.950015249877;10130.037646423578;10219.1824654026;21931.574447660852;22129.77870221277;22475.550488990455;22654.207102751323;23027.654585180902;23158.42103947415;23217.883910580338;23338.416539586648;23450.296491105295;23555.057797688587;12777.297248651414;12872.292023938773;12872.871271287167;12989.610311690665;12995.935020324838;16152.11163386025;16200.351985920432;16255.837441625905;16342.591435214215;16360.018398896103;13049.739005220032;13066;13085.214887106424;13173;13216.339183040618;12627.621171364874;12797.040221983203;13046.869531304565;13118;13288.269145197108;10989.61536346224;11187.888121119011;11192.664220073411;11197.66407007791;11201.831972520256;8789.824203516022;8876.556172191236;8946.910530894867;8965.820683586162;8981.506017169118;9138;9193.494357810385;9217.308701847522;9290.72127836166;9342.346035777702 09:07:40 INFO - PID 3797 | |4;prototype.html;555.4195865320739;594.3016955077783;594.8245267646043;596.9255340387303;597.3727586034665;6355.682215889205;6357.554971152022;6371.936280637192;6373.024362447639;6552.574082684622;6282.434580887719;6329.4619957303685;6341.239051313849;6384.457321127709;6434.517411194168;5271.9472805272035;5297.602679799012;5318.335208098988;5339.733013349327;5350.652207606513;5034.5399834048085;5293.86765330866;5301.443348448415;5307.52232299093;5328.866778330554;5063.569596584295;5084.847454576368;5112.514311140454;5119.488051194888;5146.922796158061;5120.206368012927;5124.385073791132;5130.589552835764;5133.82031628893;5139.177731562951;4644;4652.930206046912;4654.069186162781;4668.626509879202;4694.741789201593;6256.499480041586;6309.432151106423;6325.652089135096;6358.300586935434;6379.712912918883;6044.365341639153;6047.879042419171;6084.939150608526;6102.69486525672;6132.6933653317155;6599.6700164992035;6746.156730408699;6800.931990680129;6809.38715515606;6812.3187681231975;5197.194434862602;5224.39919409269;5225.817096401645;5246.291750613695;5258.894822103536;6883.552569083044;6989.930100698979;7054.0829692140205;7111.146662400493;7134.643267836639;5306.283651707047;5318;5318.335208098988;5348.5186333230195;5387.676739395649;5373.35519737634;5444.945550544523;5482.2873026507095;5488.341399032141;5492.5605951523785;5967.433093856077;5979.1928089707335;6006.279246490449;6015.127806468037;6062.151298818169;6839.965800171067;6846.315368463114;6852.349026842542;6863.931360686429;6865.622390768555;1857.5634725839418;1859.6466671332403;1880.7178923161634;1893.1196993398137;1900.429871038683;1293.5537239652306;1307.2809954524973;1330.407968454048;1343.4626149540068;1347.5889853594551;6268.777588370315;6327.892618791785;6385.393387628168;6445.291017988017;6470.608819103821;6672.633005184762;6736.966315168491;6790.219124800612;6815.965920170367;6897.172339319314;6221.96889015552;6320.45832563304;6359.650219237987;6373.203349581302;6408.455281301141;3534.8511733686655;3545.3972824619877;3566.57337065176;3600.2979419013564;3646.1796095878744;7048.859022819515;7085.89371159433;7118.71525138999;7190.943848864212;7200.387967022862;6927.549709268881;7014.579125252501;7092.538984966064;7121.679524421414;7163.2478589747325;7185.7424950632385;7235.710571577183;7242.818929526804;7249.383802376751;7267.63661816907;6811.659417029228;6828.692708828214;6832.658367081526;6857.965710171417;6912.27421120775;6515.413612774683;6695.363940425652;6760.9520524318805;6806.489513286622;6887.931120688729;6917.204521480095;6994.370506654425;7001.649917504206;7045.154581450259;7125.18060423058;5309.283246761638;5310.336207974004;5316.856875771728;5374.11327131019;5380.502673262411;6682.83292917681;6863.078338065349;6919.619420931897;6926.411255043377;6946.722131114699;6151.784687535915;6740.146459723615;6782.966085169543;6798.184217893687;6821.976703494515;6693.832654183684;6718.664066796739;6744.62904540255;6785.423239024738;6799.966000169968;6696.296888826603;6705.698243579148;6724.226713927961;6743.629100399329;6764.966175169093;6744.291849355944;6763.729450821912;6823.249442561413;6834.487413444111;6847.6576171190245;6596.241432235162;6601.01623274444;6601.636909969998;6633.402993730588;6657.633830139389 09:07:40 INFO - PID 3797 | |5;yui.html;987.7728122531818;998.4059484606657;1021.8569400283961;1031.1235449867618;1046.4767616191905;17334.219960101793;17621.11894405281;17683.469495915127;17686;19773.209071637153;20113;20342.18631254748;20381.28665496708;20405.489862753402;20410;1059.1209296284105;1068.4444089073677;1072.4155335342257;1076.7469644633504;1077.6605369308681;1097.369012817629;1118.032901540166;1121.04150950937;1121.2879821313443;1134.0530656901497;1171.8242263660477;1178.5285885645724;1179.4751335655637;1184.6682928779956;1184.946677399519;1156.259993604094;1163.2787671643634;1164.1385374822655;1170.1691798822847;1173.6185739634652;861.6424183963647;864.9913500864974;866.1598249697784;867.5965675960646;870.7387783664875;17454.650906981788;17459.650806983915;17475.912620436942;17490.47528574145;17836.554086147822;22076.448088797908;22145;22393.32820015402;22571.210007649817;22575.322740317817;16489.0930998795;16495.752563711554;16496.587585310343;16548.669026619402;16665.916670416693;4068.410080538335;4072.3687828386646;4112.691548133902;4114.794260286973;4115.54728979812;162719;163514.9999999988;163959;165093;166657;16962.321507139823;16991.91504042472;17132.486025419257;17135.400260991053;17146.39987600428;29483.557746633818;29742.25644358908;30037.39925201484;30441.69558304433;33174.336513270086;8573;8642.524661143572;8680.522571258514;8699.260562852229;8705.825883482295;3857.8456861725776;4008.3386241269864;4257.595528424795;4286.099919016979;4367.912641747147;5771.538276937835;5900;5908.261467316585;5909.3204281507315;5910.556708246899;1123.7134530694784;1162.68607475981;1162.7500087481233;1169.05306701572;1173.3839734139644;743.104559006396;754.8528037032836;760.7109298466657;769.3114662377141;773.5899973014311;11753.588624398106;11813.865147198583;12177.573784917486;12271.325077120666;12338.383080845922;715.7637979466764;726.8473620539746;732.1543617122232;741.0070505522518;743.0340557275629;5329.653572517774;5387.973060134675;5397.946020539823;5417.729113544307;5434.239206511092;151573;152546.26738929676;153464;154085;160864;135145;135282.32358838143;135825;137485;138721;113611.43194284142;116012;116496.41751791186;116701.4164929187;118389;10184.643537476153;10304.536295866554;10362.792744144925;10374.429406382722;10415.791684166426;58832;59491.40508594859;60285.39714602798;60301.69849150726;61527.07709384452;76082;76724;78636;80515;80965;16556;17008.14959252057;17069.14654267257;17141.05724185132;17266.136693165543;17220.225089871237;17245.74131388005;17377.65244695074;17413.390531331344;17661.82338176653;16539.50381488509;16805.831941680426;16956.406525771537;17091.572710682332;17098;15935.920320398323;16012.279447425128;16159;16176.110313932377;16236;16106.355745770037;16432.671346572763;16491.09298988567;16721.66556668884;16736.581585460463;11280.774384512428;11346.20576559666;11530.135239857213;11589.710257243636;11606.593769218036;12497.18768280059;12555.372231388577;12590.118691691494;12633.305168215837;12634.810477842655 09:07:40 INFO - PID 3797 | __end_tp_report 09:07:40 INFO - PID 3797 | __start_cc_report 09:07:40 INFO - PID 3797 | _x_x_mozilla_cycle_collect,254 09:07:40 INFO - PID 3797 | __end_cc_report 09:07:40 INFO - PID 3797 | __startTimestamp1506960460688__endTimestamp 09:07:40 INFO - PID 3797 | 09:07:40 INFO - PID 3797 | ------- Summary: start ------- 09:07:40 INFO - PID 3797 | Number of tests: 6 09:07:40 INFO - PID 3797 | 09:07:40 INFO - PID 3797 | [#0] dojo.html Cycles:165 Average:6528.16 Median:6764.05 stddev:760.57 (11.2%) stddev-sans-first:735.73 09:07:40 INFO - PID 3797 | Values: 3959.7 3979.7 3982.3 3993.7 4009.7 6528.7 6529.9 6563.4 6595.3 6625.3 6516.5 6527.6 6531.5 6608.9 6629.8 5860.2 5940.1 5944.3 5951.6 5966.9 5872.2 5928.9 5953.3 6006.8 6018.6 5838.1 5961.7 5973.9 6023.1 6043.3 5763.4 5883.4 5921.7 5935.1 5937.6 5758.0 5819.5 5821.4 5919.3 5933.4 6289.9 6368.8 6376.7 6395.2 6435.9 6876.7 6940.7 6964.4 6966.4 6972.6 6894.5 6954.7 6965.6 7017.2 7021.7 6475.1 6578.2 6585.4 6625.6 6720.4 7331.6 7333.3 7388.4 7388.9 7394.0 6992.0 6994.3 7000.5 7081.8 7088.5 7080.2 7135.8 7148.7 7161.4 7212.4 7000.7 7016.0 7024.8 7028.4 7030.2 6829.4 6934.9 6935.1 7016.2 7024.1 5127.3 5169.3 5195.4 5208.8 5211.8 4717.0 4765.1 4825.8 4828.0 4838.8 6594.9 6649.2 6712.2 6722.6 6729.3 7034.1 7047.2 7116.9 7139.6 7142.4 6547.2 6615.0 6627.6 6629.3 6648.2 5782.7 5882.1 5903.5 5911.1 5920.2 7268.1 7351.2 7430.8 7452.1 7460.5 6986.7 7036.8 7036.8 7039.4 7049.1 7279.2 7367.3 7377.6 7387.2 7388.3 6961.0 7020.0 7039.9 7041.2 7046.0 7151.5 7157.9 7175.0 7213.7 7218.2 6792.9 6847.9 6860.9 6880.1 6931.4 6588.2 6817.1 6817.7 6894.6 6914.4 6750.5 6764.1 6800.5 6813.6 6817.3 6838.2 6880.7 6960.6 6971.3 6974.7 6646.4 6648.0 6725.4 6734.1 6741.6 09:07:40 INFO - PID 3797 | 09:07:40 INFO - PID 3797 | [#1] ext.html Cycles:180 Average:45066.28 Median:38287.52 stddev:67201.71 (175.5%) stddev-sans-first:67308.70 09:07:40 INFO - PID 3797 | Values: 981.9 1012.8 1013.9 1017.3 1017.8 47523.8 48393.0 48421.3 48439.0 48462.0 45551.0 45714.8 45886.1 45977.8 46005.1 8908.8 9080.6 9098.0 9105.5 9109.3 8918.7 8933.3 8937.1 9020.1 9027.3 9022.7 9023.9 9037.1 9040.3 9049.7 8970.0 8974.4 8975.1 9035.2 9043.7 8837.4 8896.6 8897.2 8908.0 8921.2 42502.1 42928.1 43103.8 43276.8 43353.1 3800.7 3856.3 3868.8 3870.5 3874.5 36017.5 36031.6 36086.5 36351.8 36393.5 5486.6 5658.1 5709.6 5729.9 5736.6 240697.0 241017.0 241200.0 241791.0 241818.0 48939.3 50014.5 55881.2 56123.2 56542.0 60020.4 60164.1 60189.7 60210.1 60253.2 18389.7 18510.8 18555.2 18565.2 18591.3 15265.8 15281.8 15296.8 15319.2 15455.6 16000.7 16089.4 16097.6 16129.2 16141.2 6264.5 6367.6 6383.5 6386.5 6405.4 1132.6 1142.6 1147.7 1164.1 1166.0 26901.9 26965.9 27128.6 27186.9 27506.9 1247.7 1260.3 1268.5 1269.4 1284.7 14173.4 14268.0 14308.9 14425.6 14550.2 286773.0 286793.0 287604.0 287946.0 289327.0 61050.0 61153.4 61441.0 61452.0 61474.0 1579.8 1629.6 1630.5 1634.7 1653.3 25950.9 25987.9 26050.6 26052.7 26200.6 43463.3 43463.6 43700.1 43805.6 43918.3 244020.0 244093.0 244334.0 245327.0 248076.0 36516.0 37285.3 39462.2 40163.4 40186.8 51674.0 51692.0 51756.2 52015.5 52369.7 37577.4 37912.8 37915.2 38021.8 38075.8 37801.4 37916.4 38125.2 38274.0 38301.0 42346.2 43137.8 43319.8 43324.6 43518.3 35901.0 36007.0 36123.3 36327.6 36412.3 26632.5 26969.7 27060.3 27259.2 27281.6 09:07:40 INFO - PID 3797 | 09:07:40 INFO - PID 3797 | [#2] jquery.html Cycles:180 Average:42145.65 Median:22286.94 stddev:111054.76 (498.3%) stddev-sans-first:111322.54 09:07:40 INFO - PID 3797 | Values: 627.4 627.9 632.1 638.5 639.1 24678.9 24943.4 25401.4 25637.9 26683.3 26542.3 27845.7 27893.4 27987.6 28015.9 6692.1 6798.9 6815.5 6820.5 6822.4 6503.6 6722.5 6732.1 6753.2 6783.3 6599.1 6662.6 6714.6 6721.7 6748.8 6728.8 6731.2 6743.7 6759.8 6766.9 6276.2 6328.2 6329.3 6331.6 6335.1 31230.2 31926.0 31999.5 32268.2 32490.7 2362.0 2395.3 2398.7 2399.6 2401.7 20470.4 21054.5 21112.9 21125.9 21135.4 2605.8 2650.2 2686.4 2711.1 2717.2 138967.0 145415.3 147948.3 154956.0 155192.2 16283.7 17050.9 17311.2 17363.5 17848.4 26759.0 26789.3 26815.9 26897.6 26934.9 11333.4 11595.7 11755.2 11767.6 11849.7 10548.2 10615.6 10618.4 10667.3 10681.1 10100.8 10311.7 10343.7 10462.3 10492.9 2882.4 2904.4 2933.0 2964.6 3035.8 1048.4 1068.0 1071.7 1076.6 1077.8 22177.1 22396.8 22790.4 22817.0 22915.4 1128.5 1144.9 1148.1 1150.9 1155.0 12335.0 12468.8 12555.3 12641.7 12686.5 650222.0 650604.0 667729.0 678676.0 692423.0 131165.0 131754.0 132850.0 134312.3 135799.0 1249.7 1262.7 1298.8 1303.4 1310.3 19266.9 19938.5 19976.3 20072.6 20299.0 62819.1 64296.0 65212.3 65606.0 66189.0 80239.6 80921.0 81476.0 82515.0 84308.0 15986.8 16011.0 16341.1 16409.7 16477.0 25036.9 25125.6 25189.7 25339.1 25529.2 20854.7 20907.2 20966.3 21125.0 21179.5 18074.3 18077.6 18146.5 18269.6 18597.3 21216.8 21293.3 21445.0 21611.0 21630.4 16137.7 16176.7 16207.8 16226.4 16265.1 18178.7 18236.9 18636.1 20810.3 20997.3 09:07:40 INFO - PID 3797 | 09:07:40 INFO - PID 3797 | [#3] mootools.html Cycles:180 Average:10983.61 Median:26707.40 stddev:8136.64 (30.5%) stddev-sans-first:8127.90 09:07:40 INFO - PID 3797 | Values: 1443.9 1466.8 1480.5 1486.7 1493.5 17298.4 17874.7 17883.0 17883.6 17887.8 17552.5 17660.6 17668.2 17719.1 17733.7 2948.1 2965.1 3004.8 3015.9 3015.9 2982.3 2983.1 3001.8 3007.9 3013.9 2888.3 2912.1 2981.9 2990.5 2996.2 3006.8 3021.4 3030.6 3032.5 3032.7 2889.3 2907.9 2929.9 2932.5 2935.6 17040.9 17291.4 17341.0 17345.6 17360.1 9129.6 9434.8 9488.7 9500.9 9542.4 5314.3 5567.3 5688.4 5695.9 5717.6 2031.5 2054.7 2078.3 2119.0 2122.1 31574.4 31931.2 32026.0 32167.7 32187.4 7575.6 7577.8 7596.5 7619.5 7747.5 20029.7 20045.0 20096.0 20109.6 20133.2 8536.5 8699.6 8724.7 8753.0 8756.6 7731.5 7874.3 8314.6 8366.0 8526.5 5869.6 5876.8 6017.4 6147.4 6224.5 1596.5 1637.3 1643.9 1709.5 1730.7 1020.1 1026.1 1032.6 1037.7 1052.6 9457.2 9523.1 9550.6 9601.1 10496.7 1045.8 1058.2 1120.1 1137.3 1137.8 5789.1 6138.1 6153.7 6167.2 6224.3 26217.0 26528.7 26784.6 26909.9 26941.0 26642.7 26772.1 26875.3 26995.9 27053.2 12614.7 13176.0 13252.2 13280.3 13359.6 9832.3 9983.0 9997.0 10130.0 10219.2 21931.6 22129.8 22475.6 22654.2 23027.7 23158.4 23217.9 23338.4 23450.3 23555.1 12777.3 12872.3 12872.9 12989.6 12995.9 16152.1 16200.4 16255.8 16342.6 16360.0 13049.7 13066.0 13085.2 13173.0 13216.3 12627.6 12797.0 13046.9 13118.0 13288.3 10989.6 11187.9 11192.7 11197.7 11201.8 8789.8 8876.6 8946.9 8965.8 8981.5 9138.0 9193.5 9217.3 9290.7 9342.3 09:07:40 INFO - PID 3797 | 09:07:40 INFO - PID 3797 | [#4] prototype.html Cycles:180 Average:5776.65 Median:6372.48 stddev:1600.35 (25.1%) stddev-sans-first:1556.12 09:07:40 INFO - PID 3797 | Values: 555.4 594.3 594.8 596.9 597.4 6355.7 6357.6 6371.9 6373.0 6552.6 6282.4 6329.5 6341.2 6384.5 6434.5 5271.9 5297.6 5318.3 5339.7 5350.7 5034.5 5293.9 5301.4 5307.5 5328.9 5063.6 5084.8 5112.5 5119.5 5146.9 5120.2 5124.4 5130.6 5133.8 5139.2 4644.0 4652.9 4654.1 4668.6 4694.7 6256.5 6309.4 6325.7 6358.3 6379.7 6044.4 6047.9 6084.9 6102.7 6132.7 6599.7 6746.2 6800.9 6809.4 6812.3 5197.2 5224.4 5225.8 5246.3 5258.9 6883.6 6989.9 7054.1 7111.1 7134.6 5306.3 5318.0 5318.3 5348.5 5387.7 5373.4 5444.9 5482.3 5488.3 5492.6 5967.4 5979.2 6006.3 6015.1 6062.2 6840.0 6846.3 6852.3 6863.9 6865.6 1857.6 1859.6 1880.7 1893.1 1900.4 1293.6 1307.3 1330.4 1343.5 1347.6 6268.8 6327.9 6385.4 6445.3 6470.6 6672.6 6737.0 6790.2 6816.0 6897.2 6222.0 6320.5 6359.7 6373.2 6408.5 3534.9 3545.4 3566.6 3600.3 3646.2 7048.9 7085.9 7118.7 7190.9 7200.4 6927.5 7014.6 7092.5 7121.7 7163.2 7185.7 7235.7 7242.8 7249.4 7267.6 6811.7 6828.7 6832.7 6858.0 6912.3 6515.4 6695.4 6761.0 6806.5 6887.9 6917.2 6994.4 7001.6 7045.2 7125.2 5309.3 5310.3 5316.9 5374.1 5380.5 6682.8 6863.1 6919.6 6926.4 6946.7 6151.8 6740.1 6783.0 6798.2 6822.0 6693.8 6718.7 6744.6 6785.4 6800.0 6696.3 6705.7 6724.2 6743.6 6765.0 6744.3 6763.7 6823.2 6834.5 6847.7 6596.2 6601.0 6601.6 6633.4 6657.6 09:07:40 INFO - PID 3797 | 09:07:40 INFO - PID 3797 | [#5] yui.html Cycles:180 Average:28342.59 Median:17080.36 stddev:43936.24 (257.2%) stddev-sans-first:44011.48 09:07:40 INFO - PID 3797 | Values: 987.8 998.4 1021.9 1031.1 1046.5 17334.2 17621.1 17683.5 17686.0 19773.2 20113.0 20342.2 20381.3 20405.5 20410.0 1059.1 1068.4 1072.4 1076.7 1077.7 1097.4 1118.0 1121.0 1121.3 1134.1 1171.8 1178.5 1179.5 1184.7 1184.9 1156.3 1163.3 1164.1 1170.2 1173.6 861.6 865.0 866.2 867.6 870.7 17454.7 17459.7 17475.9 17490.5 17836.6 22076.4 22145.0 22393.3 22571.2 22575.3 16489.1 16495.8 16496.6 16548.7 16665.9 4068.4 4072.4 4112.7 4114.8 4115.5 162719.0 163515.0 163959.0 165093.0 166657.0 16962.3 16991.9 17132.5 17135.4 17146.4 29483.6 29742.3 30037.4 30441.7 33174.3 8573.0 8642.5 8680.5 8699.3 8705.8 3857.8 4008.3 4257.6 4286.1 4367.9 5771.5 5900.0 5908.3 5909.3 5910.6 1123.7 1162.7 1162.8 1169.1 1173.4 743.1 754.9 760.7 769.3 773.6 11753.6 11813.9 12177.6 12271.3 12338.4 715.8 726.8 732.2 741.0 743.0 5329.7 5388.0 5397.9 5417.7 5434.2 151573.0 152546.3 153464.0 154085.0 160864.0 135145.0 135282.3 135825.0 137485.0 138721.0 113611.4 116012.0 116496.4 116701.4 118389.0 10184.6 10304.5 10362.8 10374.4 10415.8 58832.0 59491.4 60285.4 60301.7 61527.1 76082.0 76724.0 78636.0 80515.0 80965.0 16556.0 17008.1 17069.1 17141.1 17266.1 17220.2 17245.7 17377.7 17413.4 17661.8 16539.5 16805.8 16956.4 17091.6 17098.0 15935.9 16012.3 16159.0 16176.1 16236.0 16106.4 16432.7 16491.1 16721.7 16736.6 11280.8 11346.2 11530.1 11589.7 11606.6 12497.2 12555.4 12590.1 12633.3 12634.8 09:07:40 INFO - PID 3797 | -------- Summary: end -------- 09:07:40 INFO - PID 3797 | 09:07:41 INFO - TEST-INFO | 3797: exit 0 09:07:41 INFO - TEST-OK | dromaeo_css | took 1109573ms 09:07:41 INFO - TEST-START | kraken 09:07:41 INFO - Initialising browser for kraken test... 09:07:41 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55747/getInfo.html -profile /tmp/tmpLEijzt/profile 09:07:41 INFO - TEST-INFO | started process 4176 (/builds/slave/test/build/application/firefox/firefox http://localhost:55747/getInfo.html) 09:07:46 INFO - TEST-INFO | 4176: exit 0 09:07:46 INFO - Browser initialized. 09:07:46 INFO - Running cycle 1/1 for kraken test... 09:07:46 INFO - Using env: {'DISPLAY': ':0', 09:07:46 INFO - 'HOME': '/home/cltbld', 09:07:46 INFO - 'JSGC_DISABLE_POISONING': '1', 09:07:46 INFO - 'LANG': 'en_US.UTF-8', 09:07:46 INFO - 'LANGUAGE': 'en_US:en', 09:07:46 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 09:07:46 INFO - 'LOGNAME': 'cltbld', 09:07:46 INFO - 'MAIL': '/var/mail/cltbld', 09:07:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:07:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:07:46 INFO - 'MOZ_CRASHREPORTER': '1', 09:07:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:46 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 09:07:46 INFO - 'MOZ_NO_REMOTE': '1', 09:07:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:07:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:46 INFO - 'NO_EM_RESTART': '1', 09:07:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:46 INFO - 'PWD': '/builds/slave/test', 09:07:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:07:46 INFO - 'RUST_BACKTRACE': 'full', 09:07:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:07:46 INFO - 'SHELL': '/bin/bash', 09:07:46 INFO - 'SHLVL': '1', 09:07:46 INFO - 'TERM': 'linux', 09:07:46 INFO - 'TMOUT': '86400', 09:07:46 INFO - 'USER': 'cltbld', 09:07:46 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313', 09:07:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:46 INFO - '_': '/tools/buildbot/bin/python'} 09:07:46 INFO - TEST-INFO | started process 4365 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLEijzt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:07:46 INFO - PID 4365 | 09:07:46 INFO - PID 4365 | (/builds/slave/test/build/application/firefox/firefox:4414): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:07:46 INFO - PID 4365 | 09:07:57 INFO - PID 4365 | RSS: Main: 142909440 09:07:57 INFO - PID 4365 | 09:07:59 INFO - PID 4365 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55747/tests/kraken/driver.html?testName=audio-beat-detection) 09:08:00 INFO - PID 4365 | RSS: Main: 149135360 09:08:00 INFO - PID 4365 | 09:08:03 INFO - PID 4365 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55747/tests/kraken/driver.html?testName=audio-dft) 09:08:03 INFO - PID 4365 | RSS: Main: 149102592 09:08:03 INFO - PID 4365 | 09:08:06 INFO - PID 4365 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55747/tests/kraken/driver.html?testName=audio-fft) 09:08:07 INFO - PID 4365 | RSS: Main: 151027712 09:08:07 INFO - PID 4365 | 09:08:09 INFO - PID 4365 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55747/tests/kraken/driver.html?testName=audio-oscillator) 09:08:09 INFO - PID 4365 | RSS: Main: 150937600 09:08:09 INFO - PID 4365 | 09:08:12 INFO - PID 4365 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55747/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:08:12 INFO - PID 4365 | RSS: Main: 151134208 09:08:12 INFO - PID 4365 | 09:08:15 INFO - PID 4365 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55747/tests/kraken/driver.html?testName=imaging-darkroom) 09:08:16 INFO - PID 4365 | RSS: Main: 150835200 09:08:16 INFO - PID 4365 | 09:08:19 INFO - PID 4365 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55747/tests/kraken/driver.html?testName=imaging-desaturate) 09:08:20 INFO - PID 4365 | RSS: Main: 153628672 09:08:20 INFO - PID 4365 | 09:08:23 INFO - PID 4365 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55747/tests/kraken/driver.html?testName=json-parse-financial) 09:08:23 INFO - PID 4365 | RSS: Main: 153952256 09:08:23 INFO - PID 4365 | 09:08:25 INFO - PID 4365 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55747/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:08:25 INFO - PID 4365 | RSS: Main: 153825280 09:08:26 INFO - PID 4365 | 09:08:27 INFO - PID 4365 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55747/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:08:28 INFO - PID 4365 | RSS: Main: 153903104 09:08:28 INFO - PID 4365 | 09:08:29 INFO - PID 4365 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55747/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:08:30 INFO - PID 4365 | RSS: Main: 150474752 09:08:30 INFO - PID 4365 | 09:08:33 INFO - PID 4365 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55747/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:08:33 INFO - PID 4365 | RSS: Main: 150642688 09:08:33 INFO - PID 4365 | 09:08:36 INFO - PID 4365 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55747/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:08:37 INFO - PID 4365 | RSS: Main: 151285760 09:08:37 INFO - PID 4365 | 09:08:38 INFO - PID 4365 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55747/tests/kraken/driver.html?testName=ai-astar) 09:08:39 INFO - PID 4365 | RSS: Main: 152592384 09:08:39 INFO - PID 4365 | 09:08:39 INFO - PID 4365 | __start_tp_report 09:08:39 INFO - PID 4365 | _x_x_mozilla_page_load 09:08:39 INFO - PID 4365 | _x_x_mozilla_page_load_details 09:08:39 INFO - PID 4365 | |i|pagename|runs| 09:08:39 INFO - PID 4365 | |0;ai-astar;104;105;106;106;105;107;96;105;106;106 09:08:39 INFO - PID 4365 | |1;audio-beat-detection;113;114;124;111;111;111;136;111;113;119 09:08:39 INFO - PID 4365 | |2;audio-dft;166;162;162;161;161;161;162;162;160;162 09:08:39 INFO - PID 4365 | |3;audio-fft;74;74;73;73;72;74;74;74;73;75 09:08:39 INFO - PID 4365 | |4;audio-oscillator;86;81;77;77;79;76;77;79;78;77 09:08:39 INFO - PID 4365 | |5;imaging-gaussian-blur;109;113;114;114;114;114;115;115;111;114 09:08:39 INFO - PID 4365 | |6;imaging-darkroom;149;150;149;148;150;148;149;148;152;148 09:08:39 INFO - PID 4365 | |7;imaging-desaturate;94;94;94;95;94;95;95;94;97;97 09:08:39 INFO - PID 4365 | |8;json-parse-financial;42;43;47;43;43;43;44;44;42;44 09:08:39 INFO - PID 4365 | |9;json-stringify-tinderbox;39;40;40;40;39;40;40;40;40;40 09:08:39 INFO - PID 4365 | |10;stanford-crypto-aes;73;72;69;69;73;68;71;72;71;71 09:08:39 INFO - PID 4365 | |11;stanford-crypto-ccm;105;103;108;104;103;112;104;109;106;102 09:08:39 INFO - PID 4365 | |12;stanford-crypto-pbkdf2;148;151;157;149;148;149;153;160;154;154 09:08:39 INFO - PID 4365 | |13;stanford-crypto-sha256-iterative;65;58;58;62;56;59;57;56;63;62 09:08:39 INFO - PID 4365 | __end_tp_report 09:08:39 INFO - PID 4365 | __start_cc_report 09:08:39 INFO - PID 4365 | _x_x_mozilla_cycle_collect,696 09:08:39 INFO - PID 4365 | __end_cc_report 09:08:39 INFO - PID 4365 | __startTimestamp1506960519190__endTimestamp 09:08:39 INFO - PID 4365 | 09:08:39 INFO - PID 4365 | ------- Summary: start ------- 09:08:39 INFO - PID 4365 | Number of tests: 14 09:08:39 INFO - PID 4365 | 09:08:39 INFO - PID 4365 | [#0] ai-astar Cycles:10 Average:104.60 Median:106.00 stddev:3.13 (3.0%) stddev-sans-first:3.32 09:08:39 INFO - PID 4365 | Values: 104.0 105.0 106.0 106.0 105.0 107.0 96.0 105.0 106.0 106.0 09:08:39 INFO - PID 4365 | 09:08:39 INFO - PID 4365 | [#1] audio-beat-detection Cycles:10 Average:116.30 Median:113.50 stddev:8.12 (7.2%) stddev-sans-first:8.53 09:08:39 INFO - PID 4365 | Values: 113.0 114.0 124.0 111.0 111.0 111.0 136.0 111.0 113.0 119.0 09:08:39 INFO - PID 4365 | 09:08:39 INFO - PID 4365 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:1.60 (1.0%) stddev-sans-first:0.73 09:08:39 INFO - PID 4365 | Values: 166.0 162.0 162.0 161.0 161.0 161.0 162.0 162.0 160.0 162.0 09:08:39 INFO - PID 4365 | 09:08:39 INFO - PID 4365 | [#3] audio-fft Cycles:10 Average:73.60 Median:74.00 stddev:0.84 (1.1%) stddev-sans-first:0.88 09:08:39 INFO - PID 4365 | Values: 74.0 74.0 73.0 73.0 72.0 74.0 74.0 74.0 73.0 75.0 09:08:39 INFO - PID 4365 | 09:08:39 INFO - PID 4365 | [#4] audio-oscillator Cycles:10 Average:78.70 Median:78.50 stddev:2.95 (3.8%) stddev-sans-first:1.54 09:08:39 INFO - PID 4365 | Values: 86.0 81.0 77.0 77.0 79.0 76.0 77.0 79.0 78.0 77.0 09:08:39 INFO - PID 4365 | 09:08:39 INFO - PID 4365 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:114.00 stddev:1.89 (1.7%) stddev-sans-first:1.20 09:08:39 INFO - PID 4365 | Values: 109.0 113.0 114.0 114.0 114.0 114.0 115.0 115.0 111.0 114.0 09:08:39 INFO - PID 4365 | 09:08:39 INFO - PID 4365 | [#6] imaging-darkroom Cycles:10 Average:149.10 Median:149.00 stddev:1.29 (0.9%) stddev-sans-first:1.36 09:08:39 INFO - PID 4365 | Values: 149.0 150.0 149.0 148.0 150.0 148.0 149.0 148.0 152.0 148.0 09:08:39 INFO - PID 4365 | 09:08:39 INFO - PID 4365 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:1.20 (1.3%) stddev-sans-first:1.22 09:08:39 INFO - PID 4365 | Values: 94.0 94.0 94.0 95.0 94.0 95.0 95.0 94.0 97.0 97.0 09:08:39 INFO - PID 4365 | 09:08:39 INFO - PID 4365 | [#8] json-parse-financial Cycles:10 Average:43.50 Median:43.50 stddev:1.43 (3.3%) stddev-sans-first:1.41 09:08:39 INFO - PID 4365 | Values: 42.0 43.0 47.0 43.0 43.0 43.0 44.0 44.0 42.0 44.0 09:08:39 INFO - PID 4365 | 09:08:39 INFO - PID 4365 | [#9] json-stringify-tinderbox Cycles:10 Average:39.80 Median:40.00 stddev:0.42 (1.1%) stddev-sans-first:0.33 09:08:39 INFO - PID 4365 | Values: 39.0 40.0 40.0 40.0 39.0 40.0 40.0 40.0 40.0 40.0 09:08:39 INFO - PID 4365 | 09:08:39 INFO - PID 4365 | [#10] stanford-crypto-aes Cycles:10 Average:70.90 Median:71.50 stddev:1.73 (2.4%) stddev-sans-first:1.66 09:08:39 INFO - PID 4365 | Values: 73.0 72.0 69.0 69.0 73.0 68.0 71.0 72.0 71.0 71.0 09:08:39 INFO - PID 4365 | 09:08:39 INFO - PID 4365 | [#11] stanford-crypto-ccm Cycles:10 Average:105.60 Median:105.50 stddev:3.17 (3.0%) stddev-sans-first:3.35 09:08:39 INFO - PID 4365 | Values: 105.0 103.0 108.0 104.0 103.0 112.0 104.0 109.0 106.0 102.0 09:08:39 INFO - PID 4365 | 09:08:39 INFO - PID 4365 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.30 Median:153.50 stddev:4.06 (2.6%) stddev-sans-first:3.99 09:08:39 INFO - PID 4365 | Values: 148.0 151.0 157.0 149.0 148.0 149.0 153.0 160.0 154.0 154.0 09:08:39 INFO - PID 4365 | 09:08:39 INFO - PID 4365 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.60 Median:60.50 stddev:3.17 (5.2%) stddev-sans-first:2.69 09:08:39 INFO - PID 4365 | Values: 65.0 58.0 58.0 62.0 56.0 59.0 57.0 56.0 63.0 62.0 09:08:39 INFO - PID 4365 | -------- Summary: end -------- 09:08:39 INFO - PID 4365 | 09:08:39 INFO - PID 4365 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 09:08:39 INFO - TEST-INFO | 4365: exit 0 09:08:39 INFO - TEST-OK | kraken | took 58515ms 09:08:39 INFO - SUITE-END | took 1168s 09:08:39 INFO - Completed test suite (00:19:28) 09:08:39 INFO - javascript benchmark 09:08:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9363.54035928345, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3959.742616729914, 3979.6617287530557, 3982.283189025975, 3993.7404068735536, 4009.7393669411467, 6528.706208220629, 6529.869402611945, 6563.376479234471, 6595.340465953403, 6625.337466253372, 6516.478681705464, 6527.6328468647525, 6531.542792004561, 6608.900866487006, 6629.834254143649, 5860.208871802316, 5940.079287710401, 5944.346121926606, 5951.642901425928, 5966.940330596682, 5872.17789509469, 5928.940710592904, 5953.315368732587, 6006.819795406123, 6018.578699491038, 5838.124281357788, 5961.701914904237, 5973.85065373367, 6023.126646636257, 6043.305019922699, 5763.423657634245, 5883.382244864313, 5921.674307913063, 5935.080062590284, 5937.6140550864275, 5758.0, 5819.505342045931, 5821.359650438449, 5919.260092488439, 5933.436323549256, 6289.8742025159245, 6368.8089357318895, 6376.7130479128555, 6395.232572091379, 6435.903461448082, 6876.69054892526, 6940.687669054904, 6964.4428445724225, 6966.3730264276455, 6972.616506092163, 6894.517383783137, 6954.652267386661, 6965.582065076061, 7017.157941047107, 7021.719131234744, 6475.061116138134, 6578.177727784027, 6585.407313341774, 6625.569337993015, 6720.4287635550545, 7331.596762178131, 7333.339999399973, 7388.445866559922, 7388.926110738824, 7393.963030184815, 6991.965040174869, 6994.33553812387, 7000.50996430245, 7081.82295442618, 7088.539244949062, 7080.185778635523, 7135.785926422215, 7148.678309476086, 7161.39128174111, 7212.350888420067, 7000.719971201095, 7015.964920175366, 7024.754133605299, 7028.4025857801635, 7030.226675065841, 6829.419499342612, 6934.861302773917, 6935.098437203233, 7016.193137789118, 7024.121984751906, 5127.282180494733, 5169.327987361619, 5195.428502864682, 5208.7916483341, 5211.7915283389, 4716.976415117902, 4765.094632019975, 4825.782839772219, 4828.03439312139, 4838.830640927551, 6594.934050659528, 6649.168853893263, 6712.228093769181, 6722.630255336004, 6729.327067293231, 7034.120734908137, 7047.154341479055, 7116.92883071173, 7139.643017849086, 7142.357187853014, 6547.247066587308, 6614.966925165343, 6627.635480048547, 6629.337066293332, 6648.235452922879, 5782.681952492653, 5882.088276317178, 5903.468687818117, 5911.0542313230535, 5920.200772895604, 7268.055152830098, 7351.22812104732, 7430.814229644194, 7452.06849143857, 7460.5150665207675, 6986.650667466504, 7036.824079398056, 7036.824079398056, 7039.401650859734, 7049.118860142482, 7279.23332789651, 7367.336939675455, 7377.557346559223, 7387.150477694919, 7388.298111679382, 6960.965195173992, 7019.964900175466, 7039.894401583878, 7041.225465198721, 7046.0, 7151.53515021522, 7157.85684286301, 7174.964125179341, 7213.747518836815, 7218.242084581043, 6792.932070679428, 6847.863042739217, 6860.931390686029, 6880.105586273752, 6931.445484361138, 6588.242352129567, 6817.113775209191, 6817.693203805741, 6894.620795856276, 6914.377706006282, 6750.493712971449, 6764.053032575542, 6800.455963523005, 6813.591184528944, 6817.284185160487, 6838.213605435439, 6880.69036893351, 6960.617166055713, 6971.3028697129885, 6974.6861391238535, 6646.401823835878, 6648.0, 6725.361090696376, 6734.09089772894, 6741.629210393476], "value": 6477.110883504175, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [981.8527220916861, 1012.8379459286512, 1013.8732658417698, 1017.3183966742278, 1017.801528701903, 47523.76238118809, 48393.0, 48421.27368089482, 48439.03121937563, 48462.0, 45551.0, 45714.77142614282, 45886.08227835441, 45977.770111149395, 46005.079898402095, 8908.821823563525, 9080.59137338818, 9098.044705305914, 9105.544722763869, 9109.27125829932, 8918.687845925393, 8933.330000249975, 8937.061608531109, 9020.14308640678, 9027.322950778685, 9022.684206052789, 9023.864642030341, 9037.096290370977, 9040.321975851804, 9049.683261085831, 8969.999999999967, 8974.371793974391, 8975.147361000694, 9035.232005279559, 9043.683471078513, 8837.425567338167, 8896.555172241362, 8897.199252067281, 8907.955460222658, 8921.15249051339, 42502.149957001, 42928.141437171085, 43103.78448107771, 43276.78361608172, 43353.13293734139, 3800.695944324475, 3856.3251430999744, 3868.825902834379, 3870.5161854768153, 3874.5156855393075, 36017.45973810395, 36031.63968360309, 36086.458703119475, 36351.81824090889, 36393.45409818855, 5486.561075114021, 5658.122990936411, 5709.600327977043, 5729.914051289234, 5736.598438109334, 240697.0, 241017.0, 241200.0, 241791.0, 241818.0, 48939.26591101136, 50014.49985500171, 55881.1617825733, 56123.158152627744, 56542.0, 60020.39979600148, 60164.09753853696, 60189.69905150446, 60210.096848547306, 60253.17974562025, 18389.724154137697, 18510.81489185118, 18555.16501757424, 18565.16456759449, 18591.34930277434, 15265.771013434807, 15281.770773438408, 15296.847031529765, 15319.157446340558, 15455.613609659624, 16000.679986400206, 16089.436869709503, 16097.597560060858, 16129.193540322936, 16141.192940352936, 6264.467520260738, 6367.649779262092, 6383.499877528777, 6386.521010924199, 6405.391487808651, 1132.6432173865205, 1142.5715356741223, 1147.701597584634, 1164.057113737875, 1166.0438440478727, 26901.865490672422, 26965.921363145648, 27128.593071103554, 27186.86406567994, 27506.862465687944, 1247.7255003899127, 1260.3005332040805, 1268.460904115747, 1269.3716610277972, 1284.743051389707, 14173.433062677377, 14268.013629505223, 14308.856911430961, 14425.639359016108, 14550.199739014244, 286773.0, 286793.0, 287604.0, 287946.0, 289327.0, 61050.0, 61153.38846611566, 61441.0, 61452.0, 61474.0, 1579.8104227492774, 1629.5926018495613, 1630.4537979776908, 1634.7333459670251, 1653.264297387675, 25950.87024564865, 25987.870060649577, 26050.609240861024, 26052.73947260579, 26200.606990895532, 43463.348049778644, 43463.56536434595, 43700.12599748051, 43805.56194438015, 43918.34122488229, 244020.0, 244093.0, 244334.0, 245327.0, 248076.0, 36516.0, 37285.25429491449, 39462.210755785294, 40163.397549036206, 40186.79906600448, 51674.0, 51692.0, 51756.22365664593, 52015.479845201065, 52369.738151309, 37577.4363384544, 37912.81043594764, 37915.24169516649, 38021.80989095037, 38075.80962095172, 37801.432978504796, 37916.43125353067, 38125.2374952505, 38274.0, 38301.04247393726, 42346.15307693767, 43137.7843110795, 43319.783401082794, 43324.56675433331, 43518.34722479102, 35901.0, 36007.0, 36123.27753444969, 36327.63672363242, 36412.27175456529, 26632.467350653267, 26969.73030269672, 27060.323491912073, 27259.228150170176, 27281.59077613877], "value": 19885.927540785047, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [627.3945642455033, 627.9215098112736, 632.092946621598, 638.4764493115646, 639.1020616034472, 24678.87660561697, 24943.37641558962, 25401.36496587584, 25637.87181064097, 26683.332916677093, 26542.33644158897, 27845.721542784566, 27893.442131157364, 27987.58018629717, 28015.85992070042, 6692.096566963459, 6798.932010679904, 6815.454763618924, 6820.488463365267, 6822.385985261326, 6503.577267477599, 6722.529422940396, 6732.0575119483065, 6753.15585551806, 6783.253842077367, 6599.076129341896, 6662.633555154464, 6714.6306953117555, 6721.663916804165, 6748.797536073926, 6728.764493242737, 6731.225909020452, 6743.662816859187, 6759.797206083826, 6766.932330676704, 6276.215473065867, 6328.208973878265, 6329.303776584572, 6331.620102793847, 6335.081413195107, 31230.21924451884, 31926.0, 31999.52000719991, 32268.193295167574, 32490.700371985327, 2361.9527609447887, 2395.3293077938206, 2398.712154541449, 2399.6040653292184, 2401.6637670726113, 20470.38588842337, 21054.473638159016, 21112.894435527724, 21125.894370528204, 21135.3659390217, 2605.7654811066905, 2650.2314328844614, 2686.422419179866, 2711.1053352393665, 2717.2255907066587, 138967.0, 145415.2729236347, 147948.2602586991, 154956.0, 155192.2240388791, 16283.674326513641, 17050.914745426195, 17311.220995055253, 17363.47909562715, 17848.375306864196, 26759.0, 26789.330266743487, 26815.927362905655, 26897.596536051973, 26934.865325673247, 11333.43332833355, 11595.710107247387, 11755.235909665844, 11767.646970590897, 11849.703757405961, 10548.208884333706, 10615.575376984834, 10618.415987120628, 10667.253292269466, 10681.145508359312, 10100.848487272697, 10311.74220644475, 10343.689689309333, 10462.26764126519, 10492.947535262274, 2882.437924604682, 2904.433635441111, 2932.9560056599166, 2964.614600102016, 3035.7874948753374, 1048.4286064095086, 1067.9839802402971, 1071.7250498332307, 1076.6285631457135, 1077.8113830079706, 22177.11291548352, 22396.776032239795, 22790.430239244153, 22817.0, 22915.42711432194, 1128.5372997071327, 1144.9026832719146, 1148.0930065248526, 1150.8734039255676, 1155.0413157079604, 12335.0, 12468.81296780549, 12555.309457979716, 12641.747165056648, 12686.492540298284, 650222.0, 650604.0, 667729.0, 678676.0, 692423.0, 131165.0, 131754.0, 132850.0, 134312.3284383572, 135799.0, 1249.6563445052502, 1262.6527704881048, 1298.8441387033618, 1303.4069498378406, 1310.2989900403238, 19266.903665481583, 19938.501537461678, 19976.3008294709, 20072.59854802925, 20299.0, 62819.05771413524, 64296.0, 65212.34787652063, 65606.0, 66189.0, 80239.59880200795, 80921.0, 81476.0, 82515.0, 84308.0, 15986.760198597263, 16011.039337639313, 16341.101239431946, 16409.67180656404, 16477.01137931728, 25036.874815626536, 25125.623115653645, 25189.748102519472, 25339.113131040325, 25529.23412297634, 20854.687179692013, 20907.163713451293, 20966.266180683604, 21125.0, 21179.47051323729, 18074.277028918696, 18077.638447231246, 18146.546336341697, 18269.634607308046, 18597.34909278169, 21216.78783212148, 21293.254736084164, 21445.0, 21611.0, 21630.35108946734, 16137.67724645524, 16176.67646647084, 16207.837921620632, 16226.350945962029, 16265.105419202058, 18178.72731909049, 18236.908815455838, 18636.068196590386, 20810.27164049251, 20997.265095721577], "value": 13297.169126249017, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1443.9133651980883, 1466.8093147890772, 1480.518831379802, 1486.710091532151, 1493.4623535527212, 17298.394556190535, 17874.731879021794, 17883.0, 17883.642327153448, 17887.821121788777, 17552.473425797245, 17660.558486037855, 17668.204930778113, 17719.1140442978, 17733.733993990067, 2948.0713575223735, 2965.1401093682807, 3004.8347340896244, 3015.909522714311, 3015.9396812063746, 2982.3438843454405, 2983.0603359941656, 3001.819890806548, 3007.9398412031746, 3013.8643761030808, 2888.335682792959, 2912.0535825856578, 2981.8509074546296, 2990.491616425213, 2996.2060054085687, 3006.759459243255, 3021.42592907348, 3030.5605687175457, 3032.545118232261, 3032.6815684353046, 2889.3354528458362, 2907.912762617125, 2929.8974535891352, 2932.4868148074, 2935.6036935013926, 17040.914795426066, 17291.394801181897, 17341.0, 17345.566360840952, 17360.13199340028, 9129.634814607407, 9434.764130896714, 9488.667896623585, 9500.857487137699, 9542.42745435276, 5314.256004159421, 5567.3040869891265, 5688.402717714622, 5695.886082278331, 5717.628354157008, 2031.4616626593966, 2054.7123402723632, 2078.272604588382, 2118.9682154767693, 2122.09810830398, 31574.368512629848, 31931.201719956956, 32026.039218823473, 32167.678323216936, 32187.35625287481, 7575.583342916082, 7577.772666820004, 7596.506227095221, 7619.504732192389, 7747.496412733154, 20029.699554506693, 20045.0, 20096.0, 20109.597808043756, 20133.19467221324, 8536.487810731376, 8699.565021749015, 8724.738257852274, 8752.956235218911, 8756.56217189138, 7731.536107833548, 7874.251946065115, 8314.584270786436, 8365.95817020911, 8526.531040792815, 5869.618474799125, 5876.794312199053, 6017.428344307284, 6147.385261473906, 6224.533160013018, 1596.4811436283255, 1637.2795969773263, 1643.9424620138477, 1709.495698768866, 1730.7490413890168, 1020.1125021231576, 1026.10728666061, 1032.6282538286212, 1037.7042542875245, 1052.5947510208625, 9457.243420526342, 9523.095305945924, 9550.570224339921, 9601.135897769234, 10496.68509944703, 1045.811753884293, 1058.1746237934424, 1120.0871289898707, 1137.3062431916524, 1137.7951968645557, 5789.080029917726, 6138.1099740537375, 6153.661548614779, 6167.198264225622, 6224.315325314212, 26217.0, 26528.734712652626, 26784.59823102616, 26909.86545067262, 26941.0, 26642.733572664027, 26772.062977795682, 26875.328116797238, 26995.920163193252, 27053.1884043479, 12614.675459076849, 13176.0, 13252.20486770797, 13280.269585172908, 13359.59921202346, 9832.31173817826, 9983.0, 9996.950015249877, 10130.037646423578, 10219.1824654026, 21931.574447660852, 22129.77870221277, 22475.550488990455, 22654.207102751323, 23027.654585180902, 23158.42103947415, 23217.883910580338, 23338.416539586648, 23450.296491105295, 23555.057797688587, 12777.297248651414, 12872.292023938773, 12872.871271287167, 12989.610311690665, 12995.935020324838, 16152.11163386025, 16200.351985920432, 16255.837441625905, 16342.591435214215, 16360.018398896103, 13049.739005220032, 13066.0, 13085.214887106424, 13173.0, 13216.339183040618, 12627.621171364874, 12797.040221983203, 13046.869531304565, 13118.0, 13288.269145197108, 10989.61536346224, 11187.888121119011, 11192.664220073411, 11197.66407007791, 11201.831972520256, 8789.824203516022, 8876.556172191236, 8946.910530894867, 8965.820683586162, 8981.506017169118, 9138.0, 9193.494357810385, 9217.308701847522, 9290.72127836166, 9342.346035777702], "value": 7690.293128043575, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.4195865320739, 594.3016955077783, 594.8245267646043, 596.9255340387303, 597.3727586034665, 6355.682215889205, 6357.554971152022, 6371.936280637192, 6373.024362447639, 6552.574082684622, 6282.434580887719, 6329.4619957303685, 6341.239051313849, 6384.457321127709, 6434.517411194168, 5271.9472805272035, 5297.602679799012, 5318.335208098988, 5339.733013349327, 5350.652207606513, 5034.5399834048085, 5293.86765330866, 5301.443348448415, 5307.52232299093, 5328.866778330554, 5063.569596584295, 5084.847454576368, 5112.514311140454, 5119.488051194888, 5146.922796158061, 5120.206368012927, 5124.385073791132, 5130.589552835764, 5133.82031628893, 5139.177731562951, 4644.0, 4652.930206046912, 4654.069186162781, 4668.626509879202, 4694.741789201593, 6256.499480041586, 6309.432151106423, 6325.652089135096, 6358.300586935434, 6379.712912918883, 6044.365341639153, 6047.879042419171, 6084.939150608526, 6102.69486525672, 6132.6933653317155, 6599.6700164992035, 6746.156730408699, 6800.931990680129, 6809.38715515606, 6812.3187681231975, 5197.194434862602, 5224.39919409269, 5225.817096401645, 5246.291750613695, 5258.894822103536, 6883.552569083044, 6989.930100698979, 7054.0829692140205, 7111.146662400493, 7134.643267836639, 5306.283651707047, 5318.0, 5318.335208098988, 5348.5186333230195, 5387.676739395649, 5373.35519737634, 5444.945550544523, 5482.2873026507095, 5488.341399032141, 5492.5605951523785, 5967.433093856077, 5979.1928089707335, 6006.279246490449, 6015.127806468037, 6062.151298818169, 6839.965800171067, 6846.315368463114, 6852.349026842542, 6863.931360686429, 6865.622390768555, 1857.5634725839418, 1859.6466671332403, 1880.7178923161634, 1893.1196993398137, 1900.429871038683, 1293.5537239652306, 1307.2809954524973, 1330.407968454048, 1343.4626149540068, 1347.5889853594551, 6268.777588370315, 6327.892618791785, 6385.393387628168, 6445.291017988017, 6470.608819103821, 6672.633005184762, 6736.966315168491, 6790.219124800612, 6815.965920170367, 6897.172339319314, 6221.96889015552, 6320.45832563304, 6359.650219237987, 6373.203349581302, 6408.455281301141, 3534.8511733686655, 3545.3972824619877, 3566.57337065176, 3600.2979419013564, 3646.1796095878744, 7048.859022819515, 7085.89371159433, 7118.71525138999, 7190.943848864212, 7200.387967022862, 6927.549709268881, 7014.579125252501, 7092.538984966064, 7121.679524421414, 7163.2478589747325, 7185.7424950632385, 7235.710571577183, 7242.818929526804, 7249.383802376751, 7267.63661816907, 6811.659417029228, 6828.692708828214, 6832.658367081526, 6857.965710171417, 6912.27421120775, 6515.413612774683, 6695.363940425652, 6760.9520524318805, 6806.489513286622, 6887.931120688729, 6917.204521480095, 6994.370506654425, 7001.649917504206, 7045.154581450259, 7125.18060423058, 5309.283246761638, 5310.336207974004, 5316.856875771728, 5374.11327131019, 5380.502673262411, 6682.83292917681, 6863.078338065349, 6919.619420931897, 6926.411255043377, 6946.722131114699, 6151.784687535915, 6740.146459723615, 6782.966085169543, 6798.184217893687, 6821.976703494515, 6693.832654183684, 6718.664066796739, 6744.62904540255, 6785.423239024738, 6799.966000169968, 6696.296888826603, 6705.698243579148, 6724.226713927961, 6743.629100399329, 6764.966175169093, 6744.291849355944, 6763.729450821912, 6823.249442561413, 6834.487413444111, 6847.6576171190245, 6596.241432235162, 6601.01623274444, 6601.636909969998, 6633.402993730588, 6657.633830139389], "value": 5318.502330471589, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [987.7728122531818, 998.4059484606657, 1021.8569400283961, 1031.1235449867618, 1046.4767616191905, 17334.219960101793, 17621.11894405281, 17683.469495915127, 17686.0, 19773.209071637153, 20113.0, 20342.18631254748, 20381.28665496708, 20405.489862753402, 20410.0, 1059.1209296284105, 1068.4444089073677, 1072.4155335342257, 1076.7469644633504, 1077.6605369308681, 1097.369012817629, 1118.032901540166, 1121.04150950937, 1121.2879821313443, 1134.0530656901497, 1171.8242263660477, 1178.5285885645724, 1179.4751335655637, 1184.6682928779956, 1184.946677399519, 1156.259993604094, 1163.2787671643634, 1164.1385374822655, 1170.1691798822847, 1173.6185739634652, 861.6424183963647, 864.9913500864974, 866.1598249697784, 867.5965675960646, 870.7387783664875, 17454.650906981788, 17459.650806983915, 17475.912620436942, 17490.47528574145, 17836.554086147822, 22076.448088797908, 22145.0, 22393.32820015402, 22571.210007649817, 22575.322740317817, 16489.0930998795, 16495.752563711554, 16496.587585310343, 16548.669026619402, 16665.916670416693, 4068.410080538335, 4072.3687828386646, 4112.691548133902, 4114.794260286973, 4115.54728979812, 162719.0, 163514.9999999988, 163959.0, 165093.0, 166657.0, 16962.321507139823, 16991.91504042472, 17132.486025419257, 17135.400260991053, 17146.39987600428, 29483.557746633818, 29742.25644358908, 30037.39925201484, 30441.69558304433, 33174.336513270086, 8573.0, 8642.524661143572, 8680.522571258514, 8699.260562852229, 8705.825883482295, 3857.8456861725776, 4008.3386241269864, 4257.595528424795, 4286.099919016979, 4367.912641747147, 5771.538276937835, 5900.0, 5908.261467316585, 5909.3204281507315, 5910.556708246899, 1123.7134530694784, 1162.68607475981, 1162.7500087481233, 1169.05306701572, 1173.3839734139644, 743.104559006396, 754.8528037032836, 760.7109298466657, 769.3114662377141, 773.5899973014311, 11753.588624398106, 11813.865147198583, 12177.573784917486, 12271.325077120666, 12338.383080845922, 715.7637979466764, 726.8473620539746, 732.1543617122232, 741.0070505522518, 743.0340557275629, 5329.653572517774, 5387.973060134675, 5397.946020539823, 5417.729113544307, 5434.239206511092, 151573.0, 152546.26738929676, 153464.0, 154085.0, 160864.0, 135145.0, 135282.32358838143, 135825.0, 137485.0, 138721.0, 113611.43194284142, 116012.0, 116496.41751791186, 116701.4164929187, 118389.0, 10184.643537476153, 10304.536295866554, 10362.792744144925, 10374.429406382722, 10415.791684166426, 58832.0, 59491.40508594859, 60285.39714602798, 60301.69849150726, 61527.07709384452, 76082.0, 76724.0, 78636.0, 80515.0, 80965.0, 16556.0, 17008.14959252057, 17069.14654267257, 17141.05724185132, 17266.136693165543, 17220.225089871237, 17245.74131388005, 17377.65244695074, 17413.390531331344, 17661.82338176653, 16539.50381488509, 16805.831941680426, 16956.406525771537, 17091.572710682332, 17098.0, 15935.920320398323, 16012.279447425128, 16159.0, 16176.110313932377, 16236.0, 16106.355745770037, 16432.671346572763, 16491.09298988567, 16721.66556668884, 16736.581585460463, 11280.774384512428, 11346.20576559666, 11530.135239857213, 11589.710257243636, 11606.593769218036, 12497.18768280059, 12555.372231388577, 12590.118691691494, 12633.305168215837, 12634.810477842655], "value": 9620.4495427316, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1364.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 105.0, 106.0, 106.0, 105.0, 107.0, 96.0, 105.0, 106.0, 106.0], "value": 104.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 124.0, 111.0, 111.0, 111.0, 136.0, 111.0, 113.0, 119.0], "value": 116.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 162.0, 162.0, 161.0, 161.0, 161.0, 162.0, 162.0, 160.0, 162.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 73.0, 72.0, 74.0, 74.0, 74.0, 73.0, 75.0], "value": 73.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 81.0, 77.0, 77.0, 79.0, 76.0, 77.0, 79.0, 78.0, 77.0], "value": 78.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 113.0, 114.0, 114.0, 114.0, 114.0, 115.0, 115.0, 111.0, 114.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 149.0, 148.0, 150.0, 148.0, 149.0, 148.0, 152.0, 148.0], "value": 149.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 94.0, 97.0, 97.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [42.0, 43.0, 47.0, 43.0, 43.0, 43.0, 44.0, 44.0, 42.0, 44.0], "value": 43.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 40.0, 40.0, 40.0, 39.0, 40.0, 40.0, 40.0, 40.0, 40.0], "value": 39.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 69.0, 69.0, 73.0, 68.0, 71.0, 72.0, 71.0, 71.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 103.0, 108.0, 104.0, 103.0, 112.0, 104.0, 109.0, 106.0, 102.0], "value": 105.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 151.0, 157.0, 149.0, 148.0, 149.0, 153.0, 160.0, 154.0, 154.0], "value": 152.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 58.0, 58.0, 62.0, 56.0, 59.0, 57.0, 56.0, 63.0, 62.0], "value": 59.6, "unit": "score"}]}]} 09:08:39 INFO - Return code: 0 09:08:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:08:40 INFO - # TBPL SUCCESS # 09:08:40 INFO - Running post-action listener: _package_coverage_data 09:08:40 INFO - Running post-action listener: _resource_record_post_action 09:08:40 INFO - [mozharness: 2017-10-02 16:08:40.013195Z] Finished run-tests step (success) 09:08:40 INFO - Running post-run listener: _resource_record_post_run 09:08:40 INFO - Total resource usage - Wall time: 1183s; CPU: 13.0%; Read bytes: 10756096; Write bytes: 405630976; Read time: 3176; Write time: 540700 09:08:40 INFO - TinderboxPrint: CPU usage
13.1% 09:08:40 INFO - TinderboxPrint: I/O read bytes / time
10,756,096 / 3,176 09:08:40 INFO - TinderboxPrint: I/O write bytes / time
405,630,976 / 540,700 09:08:40 INFO - TinderboxPrint: CPU idle
8,197.1 (86.9%) 09:08:40 INFO - TinderboxPrint: CPU user
1,178.5 (12.5%) 09:08:40 INFO - TinderboxPrint: Swap in / out
0 / 0 09:08:40 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 4096; Write bytes: 34488320; Read time: 132; Write time: 17280 09:08:40 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:08:40 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 10752000; Write bytes: 321425408; Read time: 3044; Write time: 445804 09:08:40 INFO - Running post-run listener: _upload_blobber_files 09:08:40 INFO - Blob upload gear active. 09:08:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:08:40 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 09:08:40 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'] 09:08:40 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 09:08:41 INFO - (blobuploader) - INFO - Open directory for files ... 09:08:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:08:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:08:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:08:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:08:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:08:42 INFO - (blobuploader) - INFO - Done attempting. 09:08:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:08:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:08:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:08:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:08:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:08:52 INFO - (blobuploader) - INFO - Done attempting. 09:08:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:08:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:08:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:08:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:08:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:08:52 INFO - (blobuploader) - INFO - Done attempting. 09:08:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:08:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:08:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:08:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:08:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:08:54 INFO - (blobuploader) - INFO - Done attempting. 09:08:54 INFO - (blobuploader) - INFO - Iteration through files over. 09:08:54 INFO - Return code: 0 09:08:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:08:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:08:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/51e70e23dd83f67817a3b6b9aac99c5e623a9feee419e10bf779e9975d5332c0123f12f34f2dd8815d6bef270d67013e0615a4db06e1833f34d6afa37d096cf8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/35edfb4b5580be15d85393f0163253ee447020f0135bbdb54da9839f1d5629e6166187387ae4db6ba0fe231f2960f4b6de7565fa4236ac92fd4da8803ffe168b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5c11369007c64202775e23949142586fa315f1149f3d4d1bc715e735f3d48a116e9bae63479a8d826e0642a2d3b15a4fed0f3c4c3a7ccc2357b4a4e91454aaf1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a0209de14e796ba06875320f29da64b08d89d92d5d832c7940ece34ee99a107beeb8fbb0a4e898e83b79671297cff5ca7a8a10ca7e91ae00054928fbeecc135e"} 09:08:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:08:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:08:54 INFO - Contents: 09:08:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/51e70e23dd83f67817a3b6b9aac99c5e623a9feee419e10bf779e9975d5332c0123f12f34f2dd8815d6bef270d67013e0615a4db06e1833f34d6afa37d096cf8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/35edfb4b5580be15d85393f0163253ee447020f0135bbdb54da9839f1d5629e6166187387ae4db6ba0fe231f2960f4b6de7565fa4236ac92fd4da8803ffe168b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5c11369007c64202775e23949142586fa315f1149f3d4d1bc715e735f3d48a116e9bae63479a8d826e0642a2d3b15a4fed0f3c4c3a7ccc2357b4a4e91454aaf1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a0209de14e796ba06875320f29da64b08d89d92d5d832c7940ece34ee99a107beeb8fbb0a4e898e83b79671297cff5ca7a8a10ca7e91ae00054928fbeecc135e"} 09:08:54 INFO - Running post-run listener: copy_logs_to_upload_dir 09:08:54 INFO - Copying logs to upload dir... 09:08:54 INFO - mkdir: /builds/slave/test/build/upload/logs 09:08:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.554383 ========= master_lag: -0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-10-02 09:08:53.757860) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-02 09:08:53.761178) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/51e70e23dd83f67817a3b6b9aac99c5e623a9feee419e10bf779e9975d5332c0123f12f34f2dd8815d6bef270d67013e0615a4db06e1833f34d6afa37d096cf8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/35edfb4b5580be15d85393f0163253ee447020f0135bbdb54da9839f1d5629e6166187387ae4db6ba0fe231f2960f4b6de7565fa4236ac92fd4da8803ffe168b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5c11369007c64202775e23949142586fa315f1149f3d4d1bc715e735f3d48a116e9bae63479a8d826e0642a2d3b15a4fed0f3c4c3a7ccc2357b4a4e91454aaf1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a0209de14e796ba06875320f29da64b08d89d92d5d832c7940ece34ee99a107beeb8fbb0a4e898e83b79671297cff5ca7a8a10ca7e91ae00054928fbeecc135e"} build_url:https://queue.taskcluster.net/v1/task/d3dkFuYKSOCZAIW9aj6NGQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011148 build_url: 'https://queue.taskcluster.net/v1/task/d3dkFuYKSOCZAIW9aj6NGQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/51e70e23dd83f67817a3b6b9aac99c5e623a9feee419e10bf779e9975d5332c0123f12f34f2dd8815d6bef270d67013e0615a4db06e1833f34d6afa37d096cf8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/35edfb4b5580be15d85393f0163253ee447020f0135bbdb54da9839f1d5629e6166187387ae4db6ba0fe231f2960f4b6de7565fa4236ac92fd4da8803ffe168b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5c11369007c64202775e23949142586fa315f1149f3d4d1bc715e735f3d48a116e9bae63479a8d826e0642a2d3b15a4fed0f3c4c3a7ccc2357b4a4e91454aaf1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a0209de14e796ba06875320f29da64b08d89d92d5d832c7940ece34ee99a107beeb8fbb0a4e898e83b79671297cff5ca7a8a10ca7e91ae00054928fbeecc135e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-02 09:08:53.793110) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 09:08:53.793496) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1506959042.836620-1949597313 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004468 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 09:08:53.874719) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-02 09:08:53.875053) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-02 09:08:53.875411) ========= ========= Total master_lag: 0.14 =========