builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-026 starttime: 1506966564.99 results: success (0) revision: c7b47e1282c2b96e8d440da60784e6dbae234770 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-02 10:49:24.989820) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-02 10:49:24.990327) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-02 10:49:24.990600) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-02 10:49:25.067809) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 10:49:25.068096) ========= 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=41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-02 10:49:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 83.7M=0s 2017-10-02 10:49:25 (83.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121994 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 10:49:25.210933) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 10:49:25.214286) ========= 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=41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015911 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 10:49:25.267533) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 10:49:25.267844) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c7b47e1282c2b96e8d440da60784e6dbae234770 --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 c7b47e1282c2b96e8d440da60784e6dbae234770 --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=41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-02 10:49:25,311 truncating revision to first 12 chars 2017-10-02 10:49:25,311 Setting DEBUG logging. 2017-10-02 10:49:25,311 attempt 1/10 2017-10-02 10:49:25,311 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/c7b47e1282c2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-02 10:49:25,761 unpacking tar archive at: mozilla-beta-c7b47e1282c2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.738058 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 10:49:26.026923) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-02 10:49:26.030381) ========= script_repo_revision: c7b47e1282c2b96e8d440da60784e6dbae234770 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-02 10:49:26.030786) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-02 10:49:26.031068) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-02 10:49:26.048204) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 54 secs) (at 2017-10-02 10:49:26.048570) ========= /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=41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:49:26 INFO - MultiFileLogger online at 20171002 10:49:26 in /builds/slave/test 10:49:26 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 10:49:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:49:26 INFO - {'append_to_log': False, 10:49:26 INFO - 'base_work_dir': '/builds/slave/test', 10:49:26 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 10:49:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:49:26 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 10:49:26 INFO - 'buildbot_json_path': 'buildprops.json', 10:49:26 INFO - 'code_coverage': False, 10:49:26 INFO - 'config_files': ('talos/linux_config.py',), 10:49:26 INFO - 'default_actions': ('clobber', 10:49:26 INFO - 'read-buildbot-config', 10:49:26 INFO - 'download-and-extract', 10:49:26 INFO - 'populate-webroot', 10:49:26 INFO - 'create-virtualenv', 10:49:26 INFO - 'install', 10:49:26 INFO - 'setup-mitmproxy', 10:49:26 INFO - 'run-tests'), 10:49:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:49:26 INFO - 'disable_ccov_upload': False, 10:49:26 INFO - 'disable_stylo': False, 10:49:26 INFO - 'download_minidump_stackwalk': True, 10:49:26 INFO - 'download_symbols': 'ondemand', 10:49:26 INFO - 'enable_stylo': False, 10:49:26 INFO - 'enable_webrender': False, 10:49:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 10:49:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:49:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:49:26 INFO - 'gecko_profile': False, 10:49:26 INFO - 'gecko_profile_interval': 0, 10:49:26 INFO - 'installer_path': 'installer.exe', 10:49:26 INFO - 'log_level': 'info', 10:49:26 INFO - 'log_name': 'talos', 10:49:26 INFO - 'log_to_console': True, 10:49:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:49:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:49:26 INFO - 'opt_config_files': (), 10:49:26 INFO - 'pip_index': False, 10:49:26 INFO - 'suite': 'dromaeojs-e10s', 10:49:26 INFO - 'system_bits': '32', 10:49:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:49:26 INFO - 'title': 'talos-linux64-ix-026', 10:49:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:49:26 INFO - 'use_talos_json': True, 10:49:26 INFO - 'verify': 'False', 10:49:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:49:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:49:26 INFO - 'work_dir': 'build'} 10:49:26 INFO - [mozharness: 2017-10-02 17:49:26.227749Z] Running clobber step. 10:49:26 INFO - Running pre-action listener: _resource_record_pre_action 10:49:26 INFO - Running main action method: clobber 10:49:26 INFO - rmtree: /builds/slave/test/build 10:49:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:49:27 INFO - Running post-action listener: _resource_record_post_action 10:49:27 INFO - [mozharness: 2017-10-02 17:49:27.207188Z] Finished clobber step (success) 10:49:27 INFO - [mozharness: 2017-10-02 17:49:27.207285Z] Running read-buildbot-config step. 10:49:27 INFO - Running pre-action listener: _resource_record_pre_action 10:49:27 INFO - Running main action method: read_buildbot_config 10:49:27 INFO - Using buildbot properties: 10:49:27 INFO - { 10:49:27 INFO - "project": "", 10:49:27 INFO - "product": "firefox", 10:49:27 INFO - "who": "ryanvm@gmail.com", 10:49:27 INFO - "installer_path": "public/build/target.tar.bz2", 10:49:27 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 10:49:27 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta", 10:49:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs-e10s", 10:49:27 INFO - "stage_platform": "linux64", 10:49:27 INFO - "basedir": "/builds/slave/test", 10:49:27 INFO - "buildnumber": 148, 10:49:27 INFO - "platform": "ubuntu64_hw", 10:49:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 10:49:27 INFO - "slavebuilddir": "test", 10:49:27 INFO - "taskId": "LsVfTzQnSQGgMQFwgbnRLQ", 10:49:27 INFO - "branch": "mozilla-beta", 10:49:27 INFO - "script_repo_revision": "production", 10:49:27 INFO - "revision": "c7b47e1282c2b96e8d440da60784e6dbae234770", 10:49:27 INFO - "slavename": "talos-linux64-ix-026", 10:49:27 INFO - "repo_path": "releases/mozilla-beta" 10:49:27 INFO - } 10:49:27 INFO - Finding installer, test and symbols from parent task. 10:49:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LsVfTzQnSQGgMQFwgbnRLQ'}, attempt #1 10:49:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LsVfTzQnSQGgMQFwgbnRLQ'}, attempt #1 10:49:29 INFO - Task dependencies: Xl0qvk8-QiGQc5almAaDCQ 10:49:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xl0qvk8-QiGQc5almAaDCQ'}, attempt #1 10:49:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Xl0qvk8-QiGQc5almAaDCQ/artifacts/public/build/target.tar.bz2 10:49:30 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Xl0qvk8-QiGQc5almAaDCQ/artifacts/public/build/target.test_packages.json 10:49:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Xl0qvk8-QiGQc5almAaDCQ/artifacts/public/build/target.crashreporter-symbols.zip 10:49:30 INFO - Running post-action listener: _resource_record_post_action 10:49:30 INFO - [mozharness: 2017-10-02 17:49:30.429407Z] Finished read-buildbot-config step (success) 10:49:30 INFO - [mozharness: 2017-10-02 17:49:30.429626Z] Running download-and-extract step. 10:49:30 INFO - Running pre-action listener: _resource_record_pre_action 10:49:30 INFO - Running main action method: download_and_extract 10:49:30 INFO - mkdir: /builds/slave/test/build/tests 10:49:30 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')]} 10:49:30 INFO - trying https://queue.taskcluster.net/v1/task/Xl0qvk8-QiGQc5almAaDCQ/artifacts/public/build/target.test_packages.json 10:49:30 INFO - Downloading https://queue.taskcluster.net/v1/task/Xl0qvk8-QiGQc5almAaDCQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 10:49:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xl0qvk8-QiGQc5almAaDCQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 10:49:32 INFO - Downloaded 989 bytes. 10:49:32 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 10:49:32 INFO - Using the following test package requirements: 10:49:32 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 10:49:32 INFO - u'common': [u'target.common.tests.zip'], 10:49:32 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 10:49:32 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 10:49:32 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 10:49:32 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 10:49:32 INFO - u'mozbase': [u'target.common.tests.zip'], 10:49:32 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 10:49:32 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 10:49:32 INFO - u'web-platform': [u'target.common.tests.zip', 10:49:32 INFO - u'target.web-platform.tests.tar.gz'], 10:49:32 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 10:49:32 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 10:49:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xl0qvk8-QiGQc5almAaDCQ/artifacts/public/build/target.common.tests.zip 10:49:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xl0qvk8-QiGQc5almAaDCQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 10:49:32 INFO - Fetch https://queue.taskcluster.net/v1/task/Xl0qvk8-QiGQc5almAaDCQ/artifacts/public/build/target.common.tests.zip into memory 10:49:34 INFO - Content-Length response header: 55181735 10:49:34 INFO - Bytes received: 55181735 10:49:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xl0qvk8-QiGQc5almAaDCQ/artifacts/public/build/target.talos.tests.zip 10:49:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xl0qvk8-QiGQc5almAaDCQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 10:49:44 INFO - Fetch https://queue.taskcluster.net/v1/task/Xl0qvk8-QiGQc5almAaDCQ/artifacts/public/build/target.talos.tests.zip into memory 10:49:45 INFO - Content-Length response header: 14509184 10:49:45 INFO - Bytes received: 14509184 10:49:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:49:45 INFO - trying https://queue.taskcluster.net/v1/task/Xl0qvk8-QiGQc5almAaDCQ/artifacts/public/build/target.tar.bz2 10:49:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Xl0qvk8-QiGQc5almAaDCQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 10:49:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xl0qvk8-QiGQc5almAaDCQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 10:49:48 INFO - Downloaded 53438889 bytes. 10:49:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Xl0qvk8-QiGQc5almAaDCQ/artifacts/public/build/target.tar.bz2 10:49:48 INFO - mkdir: /builds/slave/test/properties 10:49:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:49:48 INFO - Writing to file /builds/slave/test/properties/build_url 10:49:48 INFO - Contents: 10:49:48 INFO - build_url:https://queue.taskcluster.net/v1/task/Xl0qvk8-QiGQc5almAaDCQ/artifacts/public/build/target.tar.bz2 10:49:48 INFO - Running post-action listener: _resource_record_post_action 10:49:48 INFO - Running post-action listener: find_tests_for_verification 10:49:48 INFO - Running post-action listener: set_extra_try_arguments 10:49:48 INFO - [mozharness: 2017-10-02 17:49:48.075049Z] Finished download-and-extract step (success) 10:49:48 INFO - [mozharness: 2017-10-02 17:49:48.075145Z] Running populate-webroot step. 10:49:48 INFO - Running pre-action listener: _resource_record_pre_action 10:49:48 INFO - Running main action method: populate_webroot 10:49:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:49:48 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 10:49:48 INFO - 'tests': ['tresize', 10:49:48 INFO - 'tcanvasmark']}, 10:49:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:49:48 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 10:49:48 INFO - 'tests': ['dromaeo_css', 10:49:48 INFO - 'kraken']}, 10:49:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:49:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:49:48 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 10:49:48 INFO - 'talos_options': ['--disable-stylo'], 10:49:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:49:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:49:48 INFO - 'tests': ['damp', 'tps']}, 10:49:48 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 10:49:48 INFO - 'talos_options': ['--disable-stylo'], 10:49:48 INFO - 'tests': ['damp', 'tps']}, 10:49:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:49:48 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 10:49:48 INFO - 'tests': ['dromaeo_dom']}, 10:49:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:49:48 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 10:49:48 INFO - 'tests': ['basic_compositor_video', 10:49:48 INFO - 'glvideo']}, 10:49:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 10:49:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 10:49:48 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 10:49:48 INFO - 'talos_options': ['--disable-stylo'], 10:49:48 INFO - 'tests': ['ts_paint_webext', 10:49:48 INFO - 'tp5o_webext']}, 10:49:48 INFO - 'other-e10s': {'tests': ['a11yr', 10:49:48 INFO - 'ts_paint', 10:49:48 INFO - 'tpaint', 10:49:48 INFO - 'sessionrestore', 10:49:48 INFO - 'sessionrestore_many_windows', 10:49:48 INFO - 'sessionrestore_no_auto_restore', 10:49:48 INFO - 'tabpaint']}, 10:49:48 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 10:49:48 INFO - 'tests': ['a11yr', 10:49:48 INFO - 'ts_paint', 10:49:48 INFO - 'tpaint', 10:49:48 INFO - 'sessionrestore', 10:49:48 INFO - 'sessionrestore_many_windows', 10:49:48 INFO - 'sessionrestore_no_auto_restore', 10:49:48 INFO - 'tabpaint']}, 10:49:48 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 10:49:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 10:49:48 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 10:49:48 INFO - 'tests': ['perf_reftest_singletons']}, 10:49:48 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 10:49:48 INFO - 'tests': ['bloom_basic']}, 10:49:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:49:48 INFO - 'tsvgr_opacity', 10:49:48 INFO - 'tart', 10:49:48 INFO - 'tscrollx', 10:49:48 INFO - 'tsvg_static']}, 10:49:48 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 10:49:48 INFO - 'tests': ['tsvgx', 10:49:48 INFO - 'tsvgr_opacity', 10:49:48 INFO - 'tart', 10:49:48 INFO - 'tscrollx', 10:49:48 INFO - 'tsvg_static']}, 10:49:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:49:48 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 10:49:48 INFO - 'talos_options': ['--disable-stylo'], 10:49:48 INFO - 'tests': ['tp5o']}, 10:49:48 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 10:49:48 INFO - 'talos_options': ['--mitmproxy', 10:49:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 10:49:48 INFO - '--firstNonBlankPaint'], 10:49:48 INFO - 'tests': ['tp6_google', 10:49:48 INFO - 'tp6_youtube', 10:49:48 INFO - 'tp6_amazon', 10:49:48 INFO - 'tp6_facebook']}, 10:49:48 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 10:49:48 INFO - 'talos_options': ['--disable-stylo', 10:49:48 INFO - '--mitmproxy', 10:49:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 10:49:48 INFO - '--firstNonBlankPaint'], 10:49:48 INFO - 'tests': ['tp6_google', 10:49:48 INFO - 'tp6_youtube', 10:49:48 INFO - 'tp6_amazon', 10:49:48 INFO - 'tp6_facebook']}, 10:49:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 10:49:48 INFO - 'talos_options': ['--stylo-threads=1', 10:49:48 INFO - '--mitmproxy', 10:49:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 10:49:48 INFO - '--firstNonBlankPaint'], 10:49:48 INFO - 'tests': ['tp6_google', 10:49:48 INFO - 'tp6_youtube', 10:49:48 INFO - 'tp6_amazon', 10:49:48 INFO - 'tp6_facebook']}, 10:49:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:49:48 INFO - 'talos_options': ['--xperf_path', 10:49:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:49:48 INFO - 'tests': ['tp5n']}, 10:49:48 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 10:49:48 INFO - 'talos_options': ['--disable-stylo', 10:49:48 INFO - '--xperf_path', 10:49:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:49:48 INFO - 'tests': ['tp5n']}}} 10:49:48 INFO - Running post-action listener: _resource_record_post_action 10:49:48 INFO - [mozharness: 2017-10-02 17:49:48.089976Z] Finished populate-webroot step (success) 10:49:48 INFO - [mozharness: 2017-10-02 17:49:48.090064Z] Running create-virtualenv step. 10:49:48 INFO - Running pre-action listener: _resource_record_pre_action 10:49:48 INFO - Running main action method: create_virtualenv 10:49:48 INFO - Creating virtualenv /builds/slave/test/build/venv 10:49:48 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:49:48 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 10:49:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 10:49:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:49:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:49:48 INFO - Installing distribute.............................................................................................................................................................................................done. 10:49:48 INFO - Installing pip...............done. 10:49:48 INFO - Return code: 0 10:49:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:49:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:49:49 INFO - Reading from file tmpfile_stdout 10:49:49 INFO - Output received: 10:49:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 10:49:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:49:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:49:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:49:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:49:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:49:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1663090>, '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 0x1663d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165df90>, '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': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', '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 10:49:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:49:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:49:49 INFO - Using env: {'DISPLAY': ':0', 10:49:49 INFO - 'HOME': '/home/cltbld', 10:49:49 INFO - 'LANG': 'en_US.UTF-8', 10:49:49 INFO - 'LANGUAGE': 'en_US:en', 10:49:49 INFO - 'LOGNAME': 'cltbld', 10:49:49 INFO - 'MAIL': '/var/mail/cltbld', 10:49:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:49 INFO - 'MOZ_NO_REMOTE': '1', 10:49:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:49:49 INFO - 'NO_EM_RESTART': '1', 10:49:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:49:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:49 INFO - 'PWD': '/builds/slave/test', 10:49:49 INFO - 'SHELL': '/bin/bash', 10:49:49 INFO - 'SHLVL': '1', 10:49:49 INFO - 'TERM': 'linux', 10:49:49 INFO - 'TMOUT': '86400', 10:49:49 INFO - 'USER': 'cltbld', 10:49:49 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', 10:49:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:49 INFO - '_': '/tools/buildbot/bin/python'} 10:50:01 INFO - Ignoring indexes: http://pypi.python.org/simple/ 10:50:01 INFO - Downloading/unpacking pip>=1.5 10:50:01 INFO - HTTP error 500 while getting http://pypi.pub.build.mozilla.org/pub/pip-1.5.5.tar.gz (from http://pypi.pub.build.mozilla.org/pub/) 10:50:01 INFO - Could not install requirement pip>=1.5 because of error HTTP Error 500: Internal Server Error 10:50:01 INFO - Could not install requirement pip>=1.5 because of HTTP error HTTP Error 500: Internal Server Error for URL http://pypi.pub.build.mozilla.org/pub/pip-1.5.5.tar.gz (from http://pypi.pub.build.mozilla.org/pub/) 10:50:01 INFO - Storing complete log in /home/cltbld/.pip/pip.log 10:50:01 WARNING - Return code: 1 10:50:01 INFO - retry: Failed, sleeping 60 seconds before retrying 10:51:01 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 0x12f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1663090>, '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 0x1663d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165df90>, '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': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', '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 #2 10:51:01 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 10:51:01 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 10:51:01 INFO - Using env: {'DISPLAY': ':0', 10:51:01 INFO - 'HOME': '/home/cltbld', 10:51:01 INFO - 'LANG': 'en_US.UTF-8', 10:51:01 INFO - 'LANGUAGE': 'en_US:en', 10:51:01 INFO - 'LOGNAME': 'cltbld', 10:51:01 INFO - 'MAIL': '/var/mail/cltbld', 10:51:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:01 INFO - 'MOZ_NO_REMOTE': '1', 10:51:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:51:01 INFO - 'NO_EM_RESTART': '1', 10:51:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:51:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:01 INFO - 'PWD': '/builds/slave/test', 10:51:01 INFO - 'SHELL': '/bin/bash', 10:51:01 INFO - 'SHLVL': '1', 10:51:01 INFO - 'TERM': 'linux', 10:51:01 INFO - 'TMOUT': '86400', 10:51:01 INFO - 'USER': 'cltbld', 10:51:01 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', 10:51:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:01 INFO - '_': '/tools/buildbot/bin/python'} 10:51:03 INFO - Ignoring indexes: http://pypi.python.org/simple/ 10:51:03 INFO - Downloading/unpacking pip>=1.5 10:51:03 INFO - Running setup.py egg_info for package pip 10:51:03 INFO - warning: no files found matching 'pip/cacert.pem' 10:51:03 INFO - warning: no files found matching '*.html' under directory 'docs' 10:51:03 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 10:51:03 INFO - no previously-included directories found matching 'docs/_build/_sources' 10:51:03 INFO - Installing collected packages: pip 10:51:03 INFO - Found existing installation: pip 1.1 10:51:03 INFO - Uninstalling pip: 10:51:03 INFO - Successfully uninstalled pip 10:51:03 INFO - Running setup.py install for pip 10:51:03 INFO - warning: no files found matching 'pip/cacert.pem' 10:51:03 INFO - warning: no files found matching '*.html' under directory 'docs' 10:51:03 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 10:51:03 INFO - no previously-included directories found matching 'docs/_build/_sources' 10:51:03 INFO - Installing pip script to /builds/slave/test/build/venv/bin 10:51:03 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 10:51:03 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 10:51:03 INFO - Successfully installed pip 10:51:03 INFO - Cleaning up... 10:51:03 INFO - Return code: 0 10:51:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:51:03 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')]} 10:51:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:03 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')]} 10:51:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:03 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 0x12f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1663090>, '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 0x1663d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165df90>, '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': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', '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 10:51:03 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 10:51:03 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 10:51:03 INFO - Using env: {'DISPLAY': ':0', 10:51:03 INFO - 'HOME': '/home/cltbld', 10:51:03 INFO - 'LANG': 'en_US.UTF-8', 10:51:03 INFO - 'LANGUAGE': 'en_US:en', 10:51:03 INFO - 'LOGNAME': 'cltbld', 10:51:03 INFO - 'MAIL': '/var/mail/cltbld', 10:51:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:03 INFO - 'MOZ_NO_REMOTE': '1', 10:51:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:51:03 INFO - 'NO_EM_RESTART': '1', 10:51:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:51:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:03 INFO - 'PWD': '/builds/slave/test', 10:51:03 INFO - 'SHELL': '/bin/bash', 10:51:03 INFO - 'SHLVL': '1', 10:51:03 INFO - 'TERM': 'linux', 10:51:03 INFO - 'TMOUT': '86400', 10:51:03 INFO - 'USER': 'cltbld', 10:51:03 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', 10:51:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:03 INFO - '_': '/tools/buildbot/bin/python'} 10:51:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:03 INFO - Downloading/unpacking psutil>=3.1.1 10:51:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:51:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:51:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:51:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:51:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:51:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:51:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:51:04 INFO - Installing collected packages: psutil 10:51:04 INFO - Running setup.py install for psutil 10:51:04 INFO - building 'psutil._psutil_linux' extension 10:51:04 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 10:51:04 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 10:51:04 INFO - building 'psutil._psutil_posix' extension 10:51:04 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 10:51:04 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 10:51:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:51:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:51:04 INFO - Successfully installed psutil 10:51:04 INFO - Cleaning up... 10:51:04 INFO - Return code: 0 10:51:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:51:04 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')]} 10:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:04 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')]} 10:51:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:04 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 0x12f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1663090>, '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 0x1663d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165df90>, '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': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', '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 10:51:04 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 10:51:04 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 10:51:04 INFO - Using env: {'DISPLAY': ':0', 10:51:04 INFO - 'HOME': '/home/cltbld', 10:51:04 INFO - 'LANG': 'en_US.UTF-8', 10:51:04 INFO - 'LANGUAGE': 'en_US:en', 10:51:04 INFO - 'LOGNAME': 'cltbld', 10:51:04 INFO - 'MAIL': '/var/mail/cltbld', 10:51:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:04 INFO - 'MOZ_NO_REMOTE': '1', 10:51:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:51:04 INFO - 'NO_EM_RESTART': '1', 10:51:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:51:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:04 INFO - 'PWD': '/builds/slave/test', 10:51:04 INFO - 'SHELL': '/bin/bash', 10:51:04 INFO - 'SHLVL': '1', 10:51:04 INFO - 'TERM': 'linux', 10:51:04 INFO - 'TMOUT': '86400', 10:51:04 INFO - 'USER': 'cltbld', 10:51:04 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', 10:51:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:04 INFO - '_': '/tools/buildbot/bin/python'} 10:51:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:51:05 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 10:51:05 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 10:51:05 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 10:51:05 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 10:51:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:51:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:51:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:51:06 INFO - Installing collected packages: mozsystemmonitor 10:51:06 INFO - Running setup.py install for mozsystemmonitor 10:51:06 INFO - Successfully installed mozsystemmonitor 10:51:06 INFO - Cleaning up... 10:51:06 INFO - Return code: 0 10:51:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:51:06 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')]} 10:51:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:06 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')]} 10:51:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:06 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 0x12f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1663090>, '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 0x1663d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165df90>, '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': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', '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 10:51:06 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 10:51:06 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 10:51:06 INFO - Using env: {'DISPLAY': ':0', 10:51:06 INFO - 'HOME': '/home/cltbld', 10:51:06 INFO - 'LANG': 'en_US.UTF-8', 10:51:06 INFO - 'LANGUAGE': 'en_US:en', 10:51:06 INFO - 'LOGNAME': 'cltbld', 10:51:06 INFO - 'MAIL': '/var/mail/cltbld', 10:51:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:06 INFO - 'MOZ_NO_REMOTE': '1', 10:51:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:51:06 INFO - 'NO_EM_RESTART': '1', 10:51:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:51:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:06 INFO - 'PWD': '/builds/slave/test', 10:51:06 INFO - 'SHELL': '/bin/bash', 10:51:06 INFO - 'SHLVL': '1', 10:51:06 INFO - 'TERM': 'linux', 10:51:06 INFO - 'TMOUT': '86400', 10:51:06 INFO - 'USER': 'cltbld', 10:51:06 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', 10:51:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:06 INFO - '_': '/tools/buildbot/bin/python'} 10:51:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:06 INFO - Downloading/unpacking jsonschema==2.5.1 10:51:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:51:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:51:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:51:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:51:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:51:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:51:07 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 10:51:07 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 10:51:07 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 10:51:07 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 10:51:07 INFO - Downloading functools32-3.2.3-2.tar.gz 10:51:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:51:07 INFO - warning: no files found matching '*.txt' 10:51:07 INFO - no previously-included directories found matching 'build' 10:51:07 INFO - no previously-included directories found matching 'dist' 10:51:07 INFO - no previously-included directories found matching '.git*' 10:51:08 INFO - Installing collected packages: jsonschema, functools32 10:51:08 INFO - Running setup.py install for functools32 10:51:08 INFO - warning: no files found matching '*.txt' 10:51:08 INFO - no previously-included directories found matching 'build' 10:51:08 INFO - no previously-included directories found matching 'dist' 10:51:08 INFO - no previously-included directories found matching '.git*' 10:51:08 INFO - Successfully installed jsonschema functools32 10:51:08 INFO - Cleaning up... 10:51:08 INFO - Return code: 0 10:51:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:51:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:51:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:51:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x12f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1663090>, '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 0x1663d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165df90>, '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': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', '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 10:51:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:51:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:51:08 INFO - Using env: {'DISPLAY': ':0', 10:51:08 INFO - 'HOME': '/home/cltbld', 10:51:08 INFO - 'LANG': 'en_US.UTF-8', 10:51:08 INFO - 'LANGUAGE': 'en_US:en', 10:51:08 INFO - 'LOGNAME': 'cltbld', 10:51:08 INFO - 'MAIL': '/var/mail/cltbld', 10:51:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:08 INFO - 'MOZ_NO_REMOTE': '1', 10:51:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:51:08 INFO - 'NO_EM_RESTART': '1', 10:51:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:51:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:08 INFO - 'PWD': '/builds/slave/test', 10:51:08 INFO - 'SHELL': '/bin/bash', 10:51:08 INFO - 'SHLVL': '1', 10:51:08 INFO - 'TERM': 'linux', 10:51:08 INFO - 'TMOUT': '86400', 10:51:08 INFO - 'USER': 'cltbld', 10:51:08 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', 10:51:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:08 INFO - '_': '/tools/buildbot/bin/python'} 10:51:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:51:08 INFO - Cleaning up... 10:51:08 INFO - Return code: 0 10:51:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:51:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:51:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:51:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x12f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1663090>, '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 0x1663d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165df90>, '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': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', '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 10:51:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:51:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:51:08 INFO - Using env: {'DISPLAY': ':0', 10:51:08 INFO - 'HOME': '/home/cltbld', 10:51:08 INFO - 'LANG': 'en_US.UTF-8', 10:51:08 INFO - 'LANGUAGE': 'en_US:en', 10:51:08 INFO - 'LOGNAME': 'cltbld', 10:51:08 INFO - 'MAIL': '/var/mail/cltbld', 10:51:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:08 INFO - 'MOZ_NO_REMOTE': '1', 10:51:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:51:08 INFO - 'NO_EM_RESTART': '1', 10:51:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:51:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:08 INFO - 'PWD': '/builds/slave/test', 10:51:08 INFO - 'SHELL': '/bin/bash', 10:51:08 INFO - 'SHLVL': '1', 10:51:08 INFO - 'TERM': 'linux', 10:51:08 INFO - 'TMOUT': '86400', 10:51:08 INFO - 'USER': 'cltbld', 10:51:08 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', 10:51:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:08 INFO - '_': '/tools/buildbot/bin/python'} 10:51:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:08 INFO - Downloading/unpacking blobuploader==1.2.4 10:51:08 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 10:51:08 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 10:51:08 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 10:51:08 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 10:51:09 INFO - Downloading blobuploader-1.2.4.tar.gz 10:51:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:51:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:51:09 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 10:51:09 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 10:51:09 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 10:51:09 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 10:51:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:51:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:51:10 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 10:51:10 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 10:51:10 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 10:51:10 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 10:51:10 INFO - Downloading docopt-0.6.1.tar.gz 10:51:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:51:10 INFO - Installing collected packages: blobuploader, requests, docopt 10:51:10 INFO - Running setup.py install for blobuploader 10:51:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:51:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:51:10 INFO - Running setup.py install for requests 10:51:10 INFO - Running setup.py install for docopt 10:51:10 INFO - Successfully installed blobuploader requests docopt 10:51:10 INFO - Cleaning up... 10:51:10 INFO - Return code: 0 10:51:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:51:10 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')]} 10:51:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:10 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')]} 10:51:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:10 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 0x12f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1663090>, '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 0x1663d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165df90>, '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': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', '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 10:51:10 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 10:51:10 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 10:51:10 INFO - Using env: {'DISPLAY': ':0', 10:51:10 INFO - 'HOME': '/home/cltbld', 10:51:10 INFO - 'LANG': 'en_US.UTF-8', 10:51:10 INFO - 'LANGUAGE': 'en_US:en', 10:51:10 INFO - 'LOGNAME': 'cltbld', 10:51:10 INFO - 'MAIL': '/var/mail/cltbld', 10:51:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:10 INFO - 'MOZ_NO_REMOTE': '1', 10:51:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:51:10 INFO - 'NO_EM_RESTART': '1', 10:51:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:51:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:10 INFO - 'PWD': '/builds/slave/test', 10:51:10 INFO - 'SHELL': '/bin/bash', 10:51:10 INFO - 'SHLVL': '1', 10:51:10 INFO - 'TERM': 'linux', 10:51:10 INFO - 'TMOUT': '86400', 10:51:10 INFO - 'USER': 'cltbld', 10:51:10 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', 10:51:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:10 INFO - '_': '/tools/buildbot/bin/python'} 10:51:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:51:11 INFO - Running setup.py (path:/tmp/pip-sXtvdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:51:11 INFO - Running setup.py (path:/tmp/pip-Qo4tVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:51:11 INFO - Running setup.py (path:/tmp/pip-bQfKja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:51:11 INFO - Running setup.py (path:/tmp/pip-RRDC0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:51:11 INFO - Running setup.py (path:/tmp/pip-Fql4H5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:51:11 INFO - Running setup.py (path:/tmp/pip-6Pih1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:51:11 INFO - Running setup.py (path:/tmp/pip-eyiigE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:51:11 INFO - Running setup.py (path:/tmp/pip-hOG2PZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:51:11 INFO - Running setup.py (path:/tmp/pip-KVXnIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:51:11 INFO - Running setup.py (path:/tmp/pip-c7tMhe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:51:11 INFO - Running setup.py (path:/tmp/pip-nwZu_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:51:11 INFO - Running setup.py (path:/tmp/pip-dABnKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:51:11 INFO - Running setup.py (path:/tmp/pip-9cgrjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:51:12 INFO - Running setup.py (path:/tmp/pip-PSygLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:51:12 INFO - Running setup.py (path:/tmp/pip-rXOxIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:51:12 INFO - Running setup.py (path:/tmp/pip-bBNtHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:51:12 INFO - Running setup.py (path:/tmp/pip-skWSt5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:51:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:51:12 INFO - Running setup.py install for manifestparser 10:51:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:51:12 INFO - Running setup.py install for mozcrash 10:51:12 INFO - Running setup.py install for mozdebug 10:51:12 INFO - Running setup.py install for mozdevice 10:51:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:51:12 INFO - Running setup.py install for mozfile 10:51:12 INFO - Running setup.py install for mozhttpd 10:51:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:51:12 INFO - Running setup.py install for mozinfo 10:51:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:51:13 INFO - Running setup.py install for mozInstall 10:51:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:51:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:51:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:51:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:51:13 INFO - Running setup.py install for mozleak 10:51:13 INFO - Running setup.py install for mozlog 10:51:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:51:13 INFO - Running setup.py install for moznetwork 10:51:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:51:13 INFO - Running setup.py install for mozprocess 10:51:13 INFO - Running setup.py install for mozprofile 10:51:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:51:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:51:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:51:13 INFO - Running setup.py install for mozrunner 10:51:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:51:13 INFO - Running setup.py install for mozscreenshot 10:51:13 INFO - Running setup.py install for moztest 10:51:13 INFO - Running setup.py install for mozversion 10:51:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:51:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:51:14 INFO - Cleaning up... 10:51:14 INFO - Return code: 0 10:51:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:51:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:51:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:51:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:14 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 0x12f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1663090>, '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 0x1663d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165df90>, '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': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', '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 10:51:14 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 10:51:14 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 10:51:14 INFO - Using env: {'DISPLAY': ':0', 10:51:14 INFO - 'HOME': '/home/cltbld', 10:51:14 INFO - 'LANG': 'en_US.UTF-8', 10:51:14 INFO - 'LANGUAGE': 'en_US:en', 10:51:14 INFO - 'LOGNAME': 'cltbld', 10:51:14 INFO - 'MAIL': '/var/mail/cltbld', 10:51:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:14 INFO - 'MOZ_NO_REMOTE': '1', 10:51:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:51:14 INFO - 'NO_EM_RESTART': '1', 10:51:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:51:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:14 INFO - 'PWD': '/builds/slave/test', 10:51:14 INFO - 'SHELL': '/bin/bash', 10:51:14 INFO - 'SHLVL': '1', 10:51:14 INFO - 'TERM': 'linux', 10:51:14 INFO - 'TMOUT': '86400', 10:51:14 INFO - 'USER': 'cltbld', 10:51:14 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', 10:51:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:14 INFO - '_': '/tools/buildbot/bin/python'} 10:51:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:51:14 INFO - Running setup.py (path:/tmp/pip-plNsSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:51:14 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)) 10:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:51:14 INFO - Running setup.py (path:/tmp/pip-4Qoekf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:51:14 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)) 10:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:51:14 INFO - Running setup.py (path:/tmp/pip-2_pvHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:51:14 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)) 10:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:51:14 INFO - Running setup.py (path:/tmp/pip-45W0Dn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:51:14 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)) 10:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:51:14 INFO - Running setup.py (path:/tmp/pip-FD15sT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:51:14 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)) 10:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:51:14 INFO - Running setup.py (path:/tmp/pip-qMT8fJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:51:14 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)) 10:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:51:14 INFO - Running setup.py (path:/tmp/pip-3Bq7zF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:51:14 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)) 10:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:51:14 INFO - Running setup.py (path:/tmp/pip-H9rKT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:51:14 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)) 10:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:51:14 INFO - Running setup.py (path:/tmp/pip-PLSomq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:51:14 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)) 10:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:51:14 INFO - Running setup.py (path:/tmp/pip-OglXRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:51:15 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)) 10:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:51:15 INFO - Running setup.py (path:/tmp/pip-eVdP4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:51:15 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)) 10:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:51:15 INFO - Running setup.py (path:/tmp/pip-QqvlBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:51:15 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)) 10:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:51:15 INFO - Running setup.py (path:/tmp/pip-Fj6Ujj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:51:15 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)) 10:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:51:15 INFO - Running setup.py (path:/tmp/pip-yUiHsL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:51:15 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)) 10:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:51:15 INFO - Running setup.py (path:/tmp/pip-mim_8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:51:15 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)) 10:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:51:15 INFO - Running setup.py (path:/tmp/pip-BnZdia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:51:15 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)) 10:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:51:15 INFO - Running setup.py (path:/tmp/pip-Hretgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:51:15 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)) 10:51:15 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)) 10:51:15 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)) 10:51:15 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)) 10:51:15 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)) 10:51:15 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)) 10:51:15 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)) 10:51:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:51:15 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 10:51:15 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 10:51:15 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 10:51:15 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 10:51:16 INFO - Downloading blessings-1.6.tar.gz 10:51:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:51:16 INFO - Installing collected packages: blessings 10:51:16 INFO - Running setup.py install for blessings 10:51:16 INFO - Successfully installed blessings 10:51:16 INFO - Cleaning up... 10:51:16 INFO - Return code: 0 10:51:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:51:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:51:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:51:17 INFO - Reading from file tmpfile_stdout 10:51:17 INFO - Current package versions: 10:51:17 INFO - argparse == 1.2.1 10:51:17 INFO - blessings == 1.6 10:51:17 INFO - blobuploader == 1.2.4 10:51:17 INFO - docopt == 0.6.1 10:51:17 INFO - functools32 == 3.2.3-2 10:51:17 INFO - jsonschema == 2.5.1 10:51:17 INFO - manifestparser == 1.1 10:51:17 INFO - mozInstall == 1.13 10:51:17 INFO - mozcrash == 1.0 10:51:17 INFO - mozdebug == 0.1 10:51:17 INFO - mozdevice == 0.51 10:51:17 INFO - mozfile == 1.2 10:51:17 INFO - mozhttpd == 0.7 10:51:17 INFO - mozinfo == 0.10 10:51:17 INFO - mozleak == 0.1 10:51:17 INFO - mozlog == 3.5 10:51:17 INFO - moznetwork == 0.27 10:51:17 INFO - mozprocess == 0.25 10:51:17 INFO - mozprofile == 0.28 10:51:17 INFO - mozrunner == 6.13 10:51:17 INFO - mozscreenshot == 0.1 10:51:17 INFO - mozsystemmonitor == 0.3 10:51:17 INFO - moztest == 0.8 10:51:17 INFO - mozversion == 1.4 10:51:17 INFO - psutil == 3.1.1 10:51:17 INFO - requests == 1.2.3 10:51:17 INFO - wsgiref == 0.1.2 10:51:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:51: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')]} 10:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51: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')]} 10:51:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:17 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 0x12f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1663090>, '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 0x1663d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165df90>, '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': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', '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 10:51:17 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 10:51:17 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 10:51:17 INFO - Using env: {'DISPLAY': ':0', 10:51:17 INFO - 'HOME': '/home/cltbld', 10:51:17 INFO - 'LANG': 'en_US.UTF-8', 10:51:17 INFO - 'LANGUAGE': 'en_US:en', 10:51:17 INFO - 'LOGNAME': 'cltbld', 10:51:17 INFO - 'MAIL': '/var/mail/cltbld', 10:51:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:17 INFO - 'MOZ_NO_REMOTE': '1', 10:51:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:51:17 INFO - 'NO_EM_RESTART': '1', 10:51:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:51:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:17 INFO - 'PWD': '/builds/slave/test', 10:51:17 INFO - 'SHELL': '/bin/bash', 10:51:17 INFO - 'SHLVL': '1', 10:51:17 INFO - 'TERM': 'linux', 10:51:17 INFO - 'TMOUT': '86400', 10:51:17 INFO - 'USER': 'cltbld', 10:51:17 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', 10:51:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:17 INFO - '_': '/tools/buildbot/bin/python'} 10:51:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:17 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)) 10:51:17 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)) 10:51:17 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)) 10:51:17 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)) 10:51:17 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)) 10:51:17 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)) 10:51:17 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)) 10:51:17 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)) 10:51:17 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)) 10:51:17 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:51:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:51:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:51:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 10:51:18 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)) 10:51:18 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)) 10:51:18 INFO - Installing collected packages: simplejson 10:51:18 INFO - Running setup.py install for simplejson 10:51:18 INFO - building 'simplejson._speedups' extension 10:51:18 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 10:51:19 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 10:51:19 INFO - Successfully installed simplejson 10:51:19 INFO - Cleaning up... 10:51:19 INFO - Return code: 0 10:51:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:51:19 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')]} 10:51:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:19 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')]} 10:51:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:19 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 0x12f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1663090>, '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 0x1663d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165df90>, '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': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', '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 10:51:19 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 10:51:19 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 10:51:19 INFO - Using env: {'DISPLAY': ':0', 10:51:19 INFO - 'HOME': '/home/cltbld', 10:51:19 INFO - 'LANG': 'en_US.UTF-8', 10:51:19 INFO - 'LANGUAGE': 'en_US:en', 10:51:19 INFO - 'LOGNAME': 'cltbld', 10:51:19 INFO - 'MAIL': '/var/mail/cltbld', 10:51:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:19 INFO - 'MOZ_NO_REMOTE': '1', 10:51:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:51:19 INFO - 'NO_EM_RESTART': '1', 10:51:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:51:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:19 INFO - 'PWD': '/builds/slave/test', 10:51:19 INFO - 'SHELL': '/bin/bash', 10:51:19 INFO - 'SHLVL': '1', 10:51:19 INFO - 'TERM': 'linux', 10:51:19 INFO - 'TMOUT': '86400', 10:51:19 INFO - 'USER': 'cltbld', 10:51:19 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', 10:51:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:19 INFO - '_': '/tools/buildbot/bin/python'} 10:51:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:51:19 INFO - Cleaning up... 10:51:19 INFO - Return code: 0 10:51:19 INFO - Running post-action listener: _resource_record_post_action 10:51:19 INFO - Running post-action listener: _start_resource_monitoring 10:51:19 INFO - Starting resource monitoring. 10:51:19 INFO - [mozharness: 2017-10-02 17:51:19.783190Z] Finished create-virtualenv step (success) 10:51:19 INFO - [mozharness: 2017-10-02 17:51:19.783652Z] Running install step. 10:51:19 INFO - Running pre-action listener: _resource_record_pre_action 10:51:19 INFO - Running main action method: install 10:51:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:51:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:51:19 INFO - Reading from file tmpfile_stdout 10:51:19 INFO - Detecting whether we're running mozinstall >=1.0... 10:51:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:51:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:51:20 INFO - Reading from file tmpfile_stdout 10:51:20 INFO - Output received: 10:51:20 INFO - Usage: mozinstall [options] installer 10:51:20 INFO - Options: 10:51:20 INFO - -h, --help show this help message and exit 10:51:20 INFO - -d DEST, --destination=DEST 10:51:20 INFO - Directory to install application into. [default: 10:51:20 INFO - "/builds/slave/test"] 10:51:20 INFO - --app=APP Application being installed. [default: firefox] 10:51:20 INFO - mkdir: /builds/slave/test/build/application 10:51:20 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'] 10:51:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 10:51:34 INFO - Reading from file tmpfile_stdout 10:51:34 INFO - Output received: 10:51:34 INFO - /builds/slave/test/build/application/firefox/firefox 10:51:34 INFO - Running post-action listener: _resource_record_post_action 10:51:34 INFO - [mozharness: 2017-10-02 17:51:34.704986Z] Finished install step (success) 10:51:34 INFO - [mozharness: 2017-10-02 17:51:34.705237Z] Running setup-mitmproxy step. 10:51:34 INFO - Running pre-action listener: _resource_record_pre_action 10:51:34 INFO - Running main action method: setup_mitmproxy 10:51:34 INFO - Skipping: mitmproxy is not required 10:51:34 INFO - Running post-action listener: _resource_record_post_action 10:51:34 INFO - [mozharness: 2017-10-02 17:51:34.706355Z] Finished setup-mitmproxy step (success) 10:51:34 INFO - [mozharness: 2017-10-02 17:51:34.706690Z] Running run-tests step. 10:51:34 INFO - Running pre-action listener: _resource_record_pre_action 10:51:34 INFO - Running pre-action listener: _set_gcov_prefix 10:51:34 INFO - Running main action method: run_tests 10:51:34 WARNING - Try message not found. 10:51:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:51:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:51:34 INFO - Python 2.7.3 10:51:34 INFO - Return code: 0 10:51:34 INFO - grabbing minidump binary from tooltool 10:51:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:51:34 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 0x1663090>, '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 0x1663d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165df90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:51:34 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 10:51:34 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 10:51:34 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 10:51:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:51:34 INFO - Return code: 0 10:51:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:51:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:51:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:51:34 INFO - ENV: RUST_BACKTRACE is now full 10:51:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:51:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:51:34 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/Xl0qvk8-QiGQc5almAaDCQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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 10:51:34 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/Xl0qvk8-QiGQc5almAaDCQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-026 --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 10:51:34 INFO - Using env: {'DISPLAY': ':0', 10:51:34 INFO - 'HOME': '/home/cltbld', 10:51:34 INFO - 'LANG': 'en_US.UTF-8', 10:51:34 INFO - 'LANGUAGE': 'en_US:en', 10:51:34 INFO - 'LOGNAME': 'cltbld', 10:51:34 INFO - 'MAIL': '/var/mail/cltbld', 10:51:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:51:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:51:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:34 INFO - 'MOZ_NO_REMOTE': '1', 10:51:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:51:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:51:34 INFO - 'NO_EM_RESTART': '1', 10:51:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:51:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:34 INFO - 'PWD': '/builds/slave/test', 10:51:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:51:34 INFO - 'RUST_BACKTRACE': 'full', 10:51:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:51:34 INFO - 'SHELL': '/bin/bash', 10:51:34 INFO - 'SHLVL': '1', 10:51:34 INFO - 'TERM': 'linux', 10:51:34 INFO - 'TMOUT': '86400', 10:51:34 INFO - 'USER': 'cltbld', 10:51:34 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', 10:51:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:34 INFO - '_': '/tools/buildbot/bin/python'} 10:51:34 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/Xl0qvk8-QiGQc5almAaDCQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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 10:51:35 INFO - mozversion application_buildid: 20171002162956 10:51:35 INFO - mozversion application_changeset: c7b47e1282c2b96e8d440da60784e6dbae234770 10:51:35 INFO - mozversion application_display_name: Firefox 10:51:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:51:35 INFO - mozversion application_name: Firefox 10:51:35 INFO - mozversion application_remotingname: firefox 10:51:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:51:35 INFO - mozversion application_vendor: Mozilla 10:51:35 INFO - mozversion application_version: 57.0 10:51:35 INFO - mozversion platform_buildid: 20171002162956 10:51:35 INFO - mozversion platform_changeset: c7b47e1282c2b96e8d440da60784e6dbae234770 10:51:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:51:35 INFO - mozversion platform_version: 57.0 10:51:35 INFO - using testdate: 1506966695 10:51:35 INFO - actual date: 1506966695 10:51:35 INFO - starting webserver on 'localhost:38178' 10:51:35 INFO - SUITE-START | Running 2 tests 10:51:35 INFO - TEST-START | dromaeo_css 10:51:35 INFO - Initialising browser for dromaeo_css test... 10:51:35 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38178/getInfo.html -profile /tmp/tmpmdNoq2/profile 10:51:35 INFO - TEST-INFO | started process 7250 (/builds/slave/test/build/application/firefox/firefox http://localhost:38178/getInfo.html) 10:51:39 INFO - TEST-INFO | 7250: exit 0 10:51:40 INFO - Browser initialized. 10:51:40 INFO - Running cycle 1/1 for dromaeo_css test... 10:51:40 INFO - Using env: {'DISPLAY': ':0', 10:51:40 INFO - 'HOME': '/home/cltbld', 10:51:40 INFO - 'JSGC_DISABLE_POISONING': '1', 10:51:40 INFO - 'LANG': 'en_US.UTF-8', 10:51:40 INFO - 'LANGUAGE': 'en_US:en', 10:51:40 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 10:51:40 INFO - 'LOGNAME': 'cltbld', 10:51:40 INFO - 'MAIL': '/var/mail/cltbld', 10:51:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:51:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:51:40 INFO - 'MOZ_CRASHREPORTER': '1', 10:51:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:40 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 10:51:40 INFO - 'MOZ_NO_REMOTE': '1', 10:51:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:51:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:51:40 INFO - 'NO_EM_RESTART': '1', 10:51:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:51:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:40 INFO - 'PWD': '/builds/slave/test', 10:51:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:51:40 INFO - 'RUST_BACKTRACE': 'full', 10:51:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:51:40 INFO - 'SHELL': '/bin/bash', 10:51:40 INFO - 'SHLVL': '1', 10:51:40 INFO - 'TERM': 'linux', 10:51:40 INFO - 'TMOUT': '86400', 10:51:40 INFO - 'USER': 'cltbld', 10:51:40 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', 10:51:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:40 INFO - '_': '/tools/buildbot/bin/python'} 10:51:40 INFO - TEST-INFO | started process 7440 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmdNoq2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 10:51:40 INFO - PID 7440 | 10:51:40 INFO - PID 7440 | (/builds/slave/test/build/application/firefox/firefox:7488): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 10:51:40 INFO - PID 7440 | 10:51:51 INFO - PID 7440 | RSS: Main: 143085568 10:51:51 INFO - PID 7440 | 10:54:48 INFO - PID 7440 | Cycle 1(1): loaded http://localhost:38178/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38178/tests/dromaeo/cssquery-ext.html) 10:54:49 INFO - PID 7440 | RSS: Main: 145924096 10:54:49 INFO - PID 7440 | 10:57:51 INFO - PID 7440 | Cycle 1(1): loaded http://localhost:38178/tests/dromaeo/cssquery-ext.html (next: http://localhost:38178/tests/dromaeo/cssquery-jquery.html) 10:57:51 INFO - PID 7440 | RSS: Main: 144543744 10:57:51 INFO - PID 7440 | 11:00:53 INFO - PID 7440 | Cycle 1(1): loaded http://localhost:38178/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38178/tests/dromaeo/cssquery-mootools.html) 11:00:54 INFO - PID 7440 | RSS: Main: 145465344 11:00:54 INFO - PID 7440 | 11:03:55 INFO - PID 7440 | Cycle 1(1): loaded http://localhost:38178/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38178/tests/dromaeo/cssquery-prototype.html) 11:03:56 INFO - PID 7440 | RSS: Main: 145592320 11:03:56 INFO - PID 7440 | 11:06:59 INFO - PID 7440 | Cycle 1(1): loaded http://localhost:38178/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38178/tests/dromaeo/cssquery-yui.html) 11:06:59 INFO - PID 7440 | RSS: Main: 147087360 11:06:59 INFO - PID 7440 | 11:10:01 INFO - PID 7440 | Cycle 1(1): loaded http://localhost:38178/tests/dromaeo/cssquery-yui.html (next: http://localhost:38178/tests/dromaeo/cssquery-dojo.html) 11:10:01 INFO - PID 7440 | RSS: Main: 149929984 11:10:01 INFO - PID 7440 | 11:10:01 INFO - PID 7440 | __start_tp_report 11:10:01 INFO - PID 7440 | _x_x_mozilla_page_load 11:10:01 INFO - PID 7440 | _x_x_mozilla_page_load_details 11:10:01 INFO - PID 7440 | |i|pagename|runs| 11:10:01 INFO - PID 7440 | |0;dojo.html;4015.257177422177;4021.15555733296;4024;4035.939460908087;4046.6155715207046;6535.575187612813;6562.639054851981;6567.9671601641985;6607.933920660792;6632.2704502504685;6486.286508484063;6522.641254730988;6546.214454265495;6620.304867988864;6630.436412904897;5856.707164641773;5920.615160014607;5940.910886336697;5974.790882319098;5984.491318237955;5862.9413705863035;5934.643921364732;5985.982382994901;6019.578629495937;6036.305824830135;5983.341832398432;6009.1286763419275;6020.337762846083;6023.759049638009;6053.061775424816;5834.124881267844;5902.4097590240635;5933.406659334075;5956.731947062371;6031.788887388942;5779.682117483537;5788.507976821975;5801.854953626151;5822.243108395882;5909.586328956974;6446.516511261674;6460.741570337181;6484.546081774278;6524.510661700344;6557.180352455943;7048.083749112634;7062.364387205176;7132.215456299803;7146.892796608055;7153.642317884085;7011.228764835864;7027.613481258529;7076.080109585772;7085.185203701589;7096.396806271473;6463.5798673086565;6558.606483610999;6567.868642627169;6584.111144995412;6638.6680665966505;7241.8551628967125;7271.63641817907;7313.378362839218;7329.670164842595;7336.2296958819625;6937.202221744463;6959.095317608679;6963.6866341014775;7047.577145371294;7081.5042946994245;6925.826854328682;7080.610566418896;7106.822329441805;7113.928860711327;7116.71533138679;6946.131733533309;6999.475039372068;7016.92983070173;7070.611116388648;7130.964345178241;7000.26497217795;7078.681459334342;7102.076730025064;7110.7511237106455;7127.358537731629;5224.791008359699;5279.52484276417;5303.003802851376;5305.946940530623;5347.064263753828;4770.499097594772;4785.569298763128;4855.878603034952;4869.050535145612;4878.195097808893;6564.081028655992;6578.5395022347975;6656.534042617067;6659.86680266392;6671.566348187353;7097.432205423554;7110.146782386147;7121.109861267341;7177.246389129171;7184.389326907167;6499.5775274607;6504.642244676588;6549.377809108127;6592.9340706593275;6629.767958121539;5931.525477961753;5931.881362372728;5967.5524335675;5971.552133589998;5980.132880732356;7157.355837974607;7240.855182896313;7245.601491917995;7265.491415600857;7385.261473852572;7018.227994920657;7031.859362812817;7051.365377116084;7081.150261968597;7084.25615310385;7216.71133154689;7225.855482890208;7226.060612120391;7233.348998590152;7297.051383320135;7072.292770722886;7098.574085554883;7123.430125589836;7125;7130.144382674112;7033.120859892513;7144.857102858018;7146.071010768567;7227.638618069181;7229.240929702306;6766.289539598271;6850.554713943578;6881.862362752817;6953.304669533006;6977.162740471176;6796.830079248059;6881.41507971808;6884.4148247399535;6887;6892.138482689664;6823.761168359084;6875.89686154698;6904.171499420102;6908.3091690830515;6912.930870691229;6857.897131542931;6946.270641582763;7005.929940700732;7012.368886800213;7022.754203602849;6591.143151390289;6682.431993280626;6705.8994115087335;6706.899396509154;6716.966415167893 11:10:01 INFO - PID 7440 | |1;ext.html;992.7815880506289;1018.9949050254747;1021.5964693945891;1021.6475316015972;1025.9384436933788;47211.527884721145;48348;48439.51560484394;48466;48549.75725121374;43879.561204387945;43967.78016109915;43976.56023439765;44011.77994110033;44357;7966.641501132449;8107;8113.107558168596;8118.553479558622;8133.75598732039;7960.164182760814;8006.799830004267;8022.839543209161;8034.276915077642;8044.758657240262;7983;8746.562671866413;8897.243734282594;8904.51025193614;8905.955470222672;8779.780505487382;8803.427777194525;8838.248748856322;8838.248748856355;8855.601497932545;8708.521031343274;8832.160944710242;8841.248493878029;8845.911540884574;8852.291816654651;42111.7894410526;42161.78919105416;42328.57671423308;42372.57627423717;42480.3627945581;3802.90492737681;3820.522434695663;3828.3491806392703;3829.7893615851117;3838.865639702597;35912.28175436477;35953.28093438143;36004.63995360065;36040.09899752501;36119.09702257438;4728.645351598644;4777.211760059586;4820.325154478376;4847.175980083394;4878.0975519529;232236;232690;233013;233709;234658;50636;50766.49233507692;55980.440195598334;56473.7176314124;57526.71236643874;59620.70189649024;59721;59738;59907.40092599018;59978.70010650006;19064.33274835374;19092.71360929587;19095;19146.138423770964;19232.134553945107;15659.843401566066;15767.290471928562;15770.684586308209;15982.68034639324;16073.115978621054;15714.68570628581;15832.129232892072;15858.127802970947;15967.962082464603;15995.80031497619;6121.490189022568;6131.172291740647;6203.131561581382;6250.781222657185;6273.215848018997;1117.9944100279447;1120.9439528023565;1126.515598292742;1133.0595605647459;1135.1259530161728;26524.336891577477;26645.73354266471;26679.733202668114;27061.458770824473;27115;1237.0845574274972;1238.8946939510063;1244.166408506303;1250.0874361715998;1254.5609036837034;13822;13971.720565588834;13992.30038498071;14013.579592612237;14322.785158222636;295278;297779;298156;298726;300037;62591.374086259464;62654.43363915847;62890.37109628937;63047.684761575896;63348.366516334245;1558.345494892148;1581.7231984402683;1583.1767480909862;1596.6327744618802;1604.5982517063899;26011.219663410127;26146.215613531625;26382.736172638797;26440.8677956609;26534.336641583344;44103.117937640425;44679.776601116784;44723;44737.776311118236;44760.77619611881;243217;244029;246860;246906;246908;38089.42865856959;39101.6089839098;41285;41325.1734965305;41392;53258.46741532535;54455.45544544504;55015.17477237764;55087;55202.171967421324;39519.2096158081;39922.201555969295;40015;40035;40586.59413405828;38620.22759544737;38836.41745373765;39024.41463377995;39506.60493395029;39831;45460.318095227936;45683.54316456926;45743.54256457526;45906;46043;37118.8144059278;37264.813675931444;37526.43710344292;37859.62140378561;38178.809105955406;25870;25985;26071.217863464128;26366.472670546096;26445.867770661025 11:10:01 INFO - PID 7440 | |2;jquery.html;631.7946667333118;636.9108324834527;643.6073994863133;645.519088279232;654.0810161722779;23243.651345229835;23591.528169436602;24329.75670243297;24333.878330608346;24417.877910610423;26541;27635.447291054166;27653.585196222073;27656.032038878642;27665.170044898634;6624.13886194794;6835.111435513389;6911.03245545624;6942.166939967211;6945.270746571613;6817.897731534031;6823.420009299216;6832.453403727714;6840.692168852389;6846.5549739267035;6746.595204287734;6771;6786.524943253974;6790.083338749255;6791.422729068036;6694.698738556753;6751.122354093985;6765.492588055916;6765.695543700521;6771.72913083476;6329.8417539561415;6349.904751428732;6365.586236894587;6376.872462550769;6391.87216255677;29759;30491.085267442013;30750;30830.075097747103;30849.69150308513;2292.1289909834322;2312.7571604981404;2327.2203811723093;2331.25399872041;2340.73081595617;20348.084336204905;20957.476063098544;21020.054097565648;21043.263485777923;21063.262785802424;2530.7595778401023;2624.9212523624324;2628.080171939825;2629.368951451657;2638.0503018913178;144465.277673612;150309;163969;171112;171465.14267428708;15956.76064859028;16883.24025418859;17057.402990895258;17066.743998840026;17114.14429278531;27085.593716094274;27112.186634400998;27139;27141.86429067842;27174.048908288114;11194.776104477865;11241.83137252942;11330.37682927447;11360.829587556193;11367.261128026817;10080.3951762893;10083.647072352433;10246.795064098826;10270.281080324456;10363.533640986174;9954.950225248827;9959.502024898726;10033.147182489423;10105.595776169019;10261.333013354108;2835.702251263629;2888.797784155089;2913.920511284219;2928.1215635309322;2933.8386388748822;1048.5229220704562;1066.2003497376968;1069.9679009629724;1072.983905241422;1076.5908954597203;20898.37304880856;21299.799577162903;21668.34994950154;21670.34988950334;21821.23625673094;1103.5806393570392;1122.8428020077197;1131.298594871185;1140.3158105136852;1146.0430540498776;11522.654320370402;11626.53493860253;11721.062315014779;11855.703607409883;11858;697569;703345;711268;727582;736257;138982;139218;141124;144798.2760086193;145215;1113.716002419378;1138.3533285112148;1179.2983175010854;1262.7158889249845;1271.1356277731047;21270.149194032067;21689.240876569245;21713.457163571038;21727.782722172575;21840.67238991448;65349.01976470452;66046.33953660402;66995;67388.6630566844;68694;82997.58501207456;83553.58223208845;84194;84547.5772621133;84570;15605.297761600528;16039.599010024376;16118.838811611733;16133.274002670143;16156.757648635516;23539.411514711584;23960.682162480534;23990.28029159128;23995.760042400052;24042.87978560166;19458.610827783083;19624.313149039714;19844.50388740293;19932.20271189136;20008.099635516144;16439;16460.259288331814;16676.916615416845;16954.06752628568;17041.318347266468;19534.804651953298;19932.70100948458;20228.79771202328;20275.59448810986;20490.38528844137;15602.84397156014;15756.60608484797;15831.841681583037;15920.681586368439;15931.805114616218;19574.706379404037;19616.50958725986;19657.41027769169;19868.304609338604;19877.204911803365 11:10:01 INFO - PID 7440 | |3;mootools.html;1487.7693957436595;1531.594127556197;1538.6845696632186;1549.8682611977981;1550.2093932094642;17428.39000634978;17982.5504362391;18086.186121624527;18104.366347177853;18175;17345;17388.391406300783;17433.564160895985;17472.475825725218;17508.47474575765;3159.5418664293666;3211.710946014858;3256.560364350806;3272.247383101888;3276.1973316537483;3079.7382222511;3129;3132.7963682360687;3141.3403185330994;3152.984235078833;3004.1137864329967;3018.9396212075744;3151.543026261191;3264.5919260092487;3265.9020229393036;3076.1694342527617;3092.041467145181;3120.60992375953;3121.6410112836975;3121.797083189608;2944.263934016496;2952.2766922104006;2952.970470295291;2961.940761184764;2965.4810408178696;16784.496465106065;17198.74201886973;17228.827711722974;17258.13709314542;17310.56723581908;9226.44641321516;9509.476978766164;9573.808523829553;9610.135087842127;9610.855837162448;5918.201042859202;5944.851378715523;6400.4559612432995;6404.519661025395;6467.417932386108;2000.8599398042143;2009.6282187795305;2033.593281343737;2078.7089807426973;2081.7814129516332;31416;31607.84196079028;31641.52537711936;31673.52489712656;31690;7922.603869806487;7972.402069844785;8025.839483210303;8191.139930307351;8204.958975205085;20350.694739578918;20379.286724964553;20461.488462788548;20486.282980095624;20510.487237818874;8916.777080573038;9038.367314288069;9056.864147037799;9060.54697265134;9073.54632268384;8114.43198976066;8223.712170074019;8305.916940830515;8308.210719981713;8449.493030418194;6005.129256257818;6147.784827531015;6191.1641928339095;6217.067439883963;6235.407636274546;1711.3411336635236;1763.1096296370251;1795.9615836026205;1799.0554958646815;1800.7118860982441;851.2211326636059;1025.5180065369264;1037.2531777120462;1042.0881728487573;1064.723171975292;10027.398356098658;10043;10129.037741414553;10213.897861021444;10257.33327333721;1104.1222228328497;1106.9667909962714;1112.2881355932209;1132.5809450503366;1133.0935251798528;5800.332961709373;5919.763209471573;6207.286162091327;6447.903281450782;6455.7740479083;25748.48503029929;26139.477210455683;26291.73708262931;26430.207093787216;27027;26330.893528517674;26757.86621066921;26868;26895.193144205707;26978;12554.372281386077;13466.124701894541;13499.257540835151;13528;13559.932200338935;9565.61737530491;9694.272929530172;9766.511674416393;9771.902280977383;9952.651657191964;22211.333659990225;22363.329100127023;22522.5495490086;22628.208012720133;22912.083516659146;23040.651461247297;23172.652410214196;23254.186103486296;23383.532329353657;23900.760992390547;12326;12396.132270740962;12400;12463.065270104596;12504.749905001667;14808.111513309235;15085.396584136512;15201.087934723952;15305.08169509833;15344;12671.936640316739;12737;12774.74450510966;12779.424925878544;13244.072914895865;12336.683667867557;12484.625461236177;12489.812652810397;12572.245665260114;12618.810717839056;11266.098712102848;11320.660380188607;11437.485313161094;11439.142064345373;11453.541858325907;8139.674413023649;8242.835143297221;8305.127961563949;8306.552780596227;8326.542040187605;8497.74506764798;8667.696630617898;8706.347023973101;8717.171868672465;8875.689350872946 11:10:01 INFO - PID 7440 | |4;prototype.html;566.5071387892536;579.6840721806615;581.3198557687505;607.2379164148992;617.8486270863638;6351.460125889299;6475.287718350977;6484.4488218501365;6488.156539649851;6515;6212.868442485753;6340.23917129945;6346.397092276226;6377.968110159454;6388.680565971706;5173.586113110942;5174.689518628876;5188.948110518903;5192.688438693672;5204.817831375903;5076.822311219108;5132.871678208037;5168.2764412982215;5188;5210.114280572293;5129.1536896412235;5139.203423469368;5173.715445650488;5184.896302073957;5206.427292997767;5146.228065790143;5147.459516750725;5149.124648809711;5150.510701483353;5174.4825517448335;4708.128996135726;4723.315119307716;4735;4739.431268247798;4753.429588449409;6315.431611154972;6343.302236753953;6346;6385.96807015962;6386.616802991835;6012.669303188323;6032.517398608101;6052.455279024909;6059.36376680451;6096.603720758181;6710.228323742784;6795.18457785064;6807.727690892359;6813.557118787313;6823.215330237037;5220.895582088375;5239.240310155006;5243.397009343936;5253.028189784902;5260.421353651095;7067.257937916547;7092.219855815856;7127.8218044548785;7144.142702875636;7144.178419481774;5373.543248823894;5401.891962160735;5411.377691565442;5415.187721841677;5424.593155513273;5477.589180811455;5505.03661859173;5505.5775458798;5525.309336332958;5533.861653458615;5952.345242023374;6002.729877155539;6021.3677563856045;6060.787872424532;6069.817905462844;6519.934800652028;6773.593584384953;6815.795526134224;6828.658567071627;6849.2808255132495;1821.6629923463936;1828.44232509086;1842.1894366478707;1861.0415635947497;1869.7569315988835;1319.7228581997888;1333.1867560787912;1339.8325209348832;1345.569417786299;1347.2724728646617;6224.221972253468;6230.563860529719;6268.780592679325;6347.587406818542;6378.649174295459;6743.9325606743305;6790.93209067903;6873.5875847449315;6966.303369662993;6974.128233970754;6303.495720342362;6354.650494222862;6366.331535188831;6371.681415929185;6422.42198202164;3582.811297944831;3622.728295377857;3682.668559829628;3686.562240726119;3703.333399987974;7127.469748239404;7176.820579485555;7203.423726101899;7227.783636281909;7268.491205615662;6993.440524758007;7013.509054366247;7039.894401583981;7092.503524753321;7116.679749411289;7059.364657180879;7129.82175445618;7171.960065790536;7222.89165662526;7272.745453909088;6712.83217919556;6831.89752153728;6849.589024658537;6924.896126558207;6987.545809522365;6651.43462805667;6697.531172817856;6699.698513566804;6741.662916854236;6761.864762704817;6981.5461994970165;7043.225245222919;7065.682044307916;7108.898120791286;7158;5298.841034768963;5317.6543524672325;5328.4671532846405;5340.679559226459;5347.288867562343;6761.695723692347;6807.795766127024;6823.317668233335;6874.6562671867205;7012.824679383056;6406.935930640634;6641.203055633355;6643.867122657616;6724.159480064992;6724.663766811737;6546.509011824037;6575.30959249291;6588.40704336612;6595.373439523238;6632.336766323329;6350.618962862243;6541.247756508063;6561.507886908405;6578.76974305897;6607;6701.262861085374;6753.425958793558;6762.154730658668;6773.796786096425;6776.728930842711;6568.408843204134;6619.569727967667;6619.801405957829;6639.203295604559;6647.401733843977 11:10:01 INFO - PID 7440 | |5;yui.html;1018.1854516386893;1023.4268809466699;1034.1261633919337;1037.937723736576;1049.3074570783285;20342.6948595771;20811.167553297888;20852.478688032807;21024.369268921917;21089.57820843582;18814.905925470386;18917.810821891777;18919.810801891977;18974.62050758984;19006;1050.6585359758071;1062.7236918401213;1063.462951209638;1071.8874518175596;1073.2433634287809;1089.2810744908363;1091.2088735666619;1096.2162054131295;1096.8628921384827;1097.5664612478067;1048.6539441984164;1136.6192325570903;1136.9545218191263;1140.401289323108;1145.37004647444;1129.3675541696675;1129.7232178116415;1134.7900638381884;1135.4152611405134;1141.737400397909;839.17760594617;840.9495430274142;842.3177226446597;842.7640260726945;844.7339088187201;20454.250227324876;20773.16907323705;21086.5782684347;21087.051082701317;21905.342839714835;24361.512769744684;24416.51166976668;24764.25707228786;24896.875515622487;24898.87550562254;18851.811481885143;18888.81111188884;19223;19233.326833560757;19234.519137021547;3950.1309711863346;4007.338789099795;4022.9798851005557;4025;4031.294523458383;131532;158079.2096039524;172000.1399992992;174295.99999999872;174320;19712.70430943537;19956;20112.296069637494;20113.09491072905;20284.695729564068;36250.8187459061;36265.81867090701;36324.81837590848;36735.265294694495;36810.447843282374;10021.498925053718;10271.767387913349;10339.689809305733;10351.06840384369;10356.689299321033;4891.315215869781;4951.207806750903;4995;5007.624428167902;5014.949850501521;6597.208334999831;6604.14146160996;6651.33486651331;6724.293949135368;6768.187817461936;1208.788462019143;1211;1212.6240865331774;1229.7048708309942;1234.0312854409246;736.1313649893177;757.6969212315119;759.6847308367077;781.0861292288037;787.4251796188814;13542.864571354357;13616.319184040758;13752.31238438074;13985.79021314681;14110.506132285525;766.7009866152205;778.1207235823483;783.8824176373589;784.9136594974548;789.9565523896126;6742.258351581322;6754.189497260262;6772.322767723188;6774.288699686561;6799.93200067993;151762;152209.23895380675;155577;155814;163772;131271;132927;133162;139106;143389;113614;114510;117597.41201293939;119076.40461797809;120002.39998800124;13808.585742427744;14001;14031.36858841375;14113.717725645225;14236.715265694835;57236.71381643065;57242;57775.422245777;58203.4179658198;58454.41545584489;68719.65640171967;69104.6544767273;69184;69289.65355173394;69701;20600.38198854037;20993.37019889406;21053.473663157933;21091.472713182295;21170.9414529276;23551.52896942086;23687.407814804767;23932.641010385207;24298;24311.392215194763;19888;20380.79619203789;20445.693314599994;20451.079701413768;20567.58864822665;20513.794862051785;20841;20850.478738031063;20887.58224835525;20888.895555522126;19789.703154452982;19850.801491984894;19896.104675289356;20123.698144527552;20375.4906127348;14722.55832325032;14832;14967.550973470814;14970.775438368652;15004.099754014795;14689.853101468849;15150.848491514944;15264.694706105594;15280.847191527942;15359.61600959985 11:10:01 INFO - PID 7440 | __end_tp_report 11:10:01 INFO - PID 7440 | __start_cc_report 11:10:01 INFO - PID 7440 | _x_x_mozilla_cycle_collect,254 11:10:01 INFO - PID 7440 | __end_cc_report 11:10:01 INFO - PID 7440 | __startTimestamp1506967801422__endTimestamp 11:10:01 INFO - PID 7440 | 11:10:01 INFO - PID 7440 | ------- Summary: start ------- 11:10:01 INFO - PID 7440 | Number of tests: 6 11:10:01 INFO - PID 7440 | 11:10:01 INFO - PID 7440 | [#0] dojo.html Cycles:165 Average:6547.22 Median:6823.76 stddev:743.73 (10.9%) stddev-sans-first:719.00 11:10:01 INFO - PID 7440 | Values: 4015.3 4021.2 4024.0 4035.9 4046.6 6535.6 6562.6 6568.0 6607.9 6632.3 6486.3 6522.6 6546.2 6620.3 6630.4 5856.7 5920.6 5940.9 5974.8 5984.5 5862.9 5934.6 5986.0 6019.6 6036.3 5983.3 6009.1 6020.3 6023.8 6053.1 5834.1 5902.4 5933.4 5956.7 6031.8 5779.7 5788.5 5801.9 5822.2 5909.6 6446.5 6460.7 6484.5 6524.5 6557.2 7048.1 7062.4 7132.2 7146.9 7153.6 7011.2 7027.6 7076.1 7085.2 7096.4 6463.6 6558.6 6567.9 6584.1 6638.7 7241.9 7271.6 7313.4 7329.7 7336.2 6937.2 6959.1 6963.7 7047.6 7081.5 6925.8 7080.6 7106.8 7113.9 7116.7 6946.1 6999.5 7016.9 7070.6 7131.0 7000.3 7078.7 7102.1 7110.8 7127.4 5224.8 5279.5 5303.0 5305.9 5347.1 4770.5 4785.6 4855.9 4869.1 4878.2 6564.1 6578.5 6656.5 6659.9 6671.6 7097.4 7110.1 7121.1 7177.2 7184.4 6499.6 6504.6 6549.4 6592.9 6629.8 5931.5 5931.9 5967.6 5971.6 5980.1 7157.4 7240.9 7245.6 7265.5 7385.3 7018.2 7031.9 7051.4 7081.2 7084.3 7216.7 7225.9 7226.1 7233.3 7297.1 7072.3 7098.6 7123.4 7125.0 7130.1 7033.1 7144.9 7146.1 7227.6 7229.2 6766.3 6850.6 6881.9 6953.3 6977.2 6796.8 6881.4 6884.4 6887.0 6892.1 6823.8 6875.9 6904.2 6908.3 6912.9 6857.9 6946.3 7005.9 7012.4 7022.8 6591.1 6682.4 6705.9 6706.9 6717.0 11:10:01 INFO - PID 7440 | 11:10:01 INFO - PID 7440 | [#1] ext.html Cycles:180 Average:45352.69 Median:39675.10 stddev:67715.39 (170.7%) stddev-sans-first:67823.43 11:10:01 INFO - PID 7440 | Values: 992.8 1019.0 1021.6 1021.6 1025.9 47211.5 48348.0 48439.5 48466.0 48549.8 43879.6 43967.8 43976.6 44011.8 44357.0 7966.6 8107.0 8113.1 8118.6 8133.8 7960.2 8006.8 8022.8 8034.3 8044.8 7983.0 8746.6 8897.2 8904.5 8906.0 8779.8 8803.4 8838.2 8838.2 8855.6 8708.5 8832.2 8841.2 8845.9 8852.3 42111.8 42161.8 42328.6 42372.6 42480.4 3802.9 3820.5 3828.3 3829.8 3838.9 35912.3 35953.3 36004.6 36040.1 36119.1 4728.6 4777.2 4820.3 4847.2 4878.1 232236.0 232690.0 233013.0 233709.0 234658.0 50636.0 50766.5 55980.4 56473.7 57526.7 59620.7 59721.0 59738.0 59907.4 59978.7 19064.3 19092.7 19095.0 19146.1 19232.1 15659.8 15767.3 15770.7 15982.7 16073.1 15714.7 15832.1 15858.1 15968.0 15995.8 6121.5 6131.2 6203.1 6250.8 6273.2 1118.0 1120.9 1126.5 1133.1 1135.1 26524.3 26645.7 26679.7 27061.5 27115.0 1237.1 1238.9 1244.2 1250.1 1254.6 13822.0 13971.7 13992.3 14013.6 14322.8 295278.0 297779.0 298156.0 298726.0 300037.0 62591.4 62654.4 62890.4 63047.7 63348.4 1558.3 1581.7 1583.2 1596.6 1604.6 26011.2 26146.2 26382.7 26440.9 26534.3 44103.1 44679.8 44723.0 44737.8 44760.8 243217.0 244029.0 246860.0 246906.0 246908.0 38089.4 39101.6 41285.0 41325.2 41392.0 53258.5 54455.5 55015.2 55087.0 55202.2 39519.2 39922.2 40015.0 40035.0 40586.6 38620.2 38836.4 39024.4 39506.6 39831.0 45460.3 45683.5 45743.5 45906.0 46043.0 37118.8 37264.8 37526.4 37859.6 38178.8 25870.0 25985.0 26071.2 26366.5 26445.9 11:10:01 INFO - PID 7440 | 11:10:01 INFO - PID 7440 | [#2] jquery.html Cycles:180 Average:43857.02 Median:21774.51 stddev:119105.24 (547.0%) stddev-sans-first:119395.14 11:10:01 INFO - PID 7440 | Values: 631.8 636.9 643.6 645.5 654.1 23243.7 23591.5 24329.8 24333.9 24417.9 26541.0 27635.4 27653.6 27656.0 27665.2 6624.1 6835.1 6911.0 6942.2 6945.3 6817.9 6823.4 6832.5 6840.7 6846.6 6746.6 6771.0 6786.5 6790.1 6791.4 6694.7 6751.1 6765.5 6765.7 6771.7 6329.8 6349.9 6365.6 6376.9 6391.9 29759.0 30491.1 30750.0 30830.1 30849.7 2292.1 2312.8 2327.2 2331.3 2340.7 20348.1 20957.5 21020.1 21043.3 21063.3 2530.8 2624.9 2628.1 2629.4 2638.1 144465.3 150309.0 163969.0 171112.0 171465.1 15956.8 16883.2 17057.4 17066.7 17114.1 27085.6 27112.2 27139.0 27141.9 27174.0 11194.8 11241.8 11330.4 11360.8 11367.3 10080.4 10083.6 10246.8 10270.3 10363.5 9955.0 9959.5 10033.1 10105.6 10261.3 2835.7 2888.8 2913.9 2928.1 2933.8 1048.5 1066.2 1070.0 1073.0 1076.6 20898.4 21299.8 21668.3 21670.3 21821.2 1103.6 1122.8 1131.3 1140.3 1146.0 11522.7 11626.5 11721.1 11855.7 11858.0 697569.0 703345.0 711268.0 727582.0 736257.0 138982.0 139218.0 141124.0 144798.3 145215.0 1113.7 1138.4 1179.3 1262.7 1271.1 21270.1 21689.2 21713.5 21727.8 21840.7 65349.0 66046.3 66995.0 67388.7 68694.0 82997.6 83553.6 84194.0 84547.6 84570.0 15605.3 16039.6 16118.8 16133.3 16156.8 23539.4 23960.7 23990.3 23995.8 24042.9 19458.6 19624.3 19844.5 19932.2 20008.1 16439.0 16460.3 16676.9 16954.1 17041.3 19534.8 19932.7 20228.8 20275.6 20490.4 15602.8 15756.6 15831.8 15920.7 15931.8 19574.7 19616.5 19657.4 19868.3 19877.2 11:10:01 INFO - PID 7440 | 11:10:01 INFO - PID 7440 | [#3] mootools.html Cycles:180 Average:10957.67 Median:26215.61 stddev:8036.28 (30.7%) stddev-sans-first:8027.33 11:10:01 INFO - PID 7440 | Values: 1487.8 1531.6 1538.7 1549.9 1550.2 17428.4 17982.6 18086.2 18104.4 18175.0 17345.0 17388.4 17433.6 17472.5 17508.5 3159.5 3211.7 3256.6 3272.2 3276.2 3079.7 3129.0 3132.8 3141.3 3153.0 3004.1 3018.9 3151.5 3264.6 3265.9 3076.2 3092.0 3120.6 3121.6 3121.8 2944.3 2952.3 2953.0 2961.9 2965.5 16784.5 17198.7 17228.8 17258.1 17310.6 9226.4 9509.5 9573.8 9610.1 9610.9 5918.2 5944.9 6400.5 6404.5 6467.4 2000.9 2009.6 2033.6 2078.7 2081.8 31416.0 31607.8 31641.5 31673.5 31690.0 7922.6 7972.4 8025.8 8191.1 8205.0 20350.7 20379.3 20461.5 20486.3 20510.5 8916.8 9038.4 9056.9 9060.5 9073.5 8114.4 8223.7 8305.9 8308.2 8449.5 6005.1 6147.8 6191.2 6217.1 6235.4 1711.3 1763.1 1796.0 1799.1 1800.7 851.2 1025.5 1037.3 1042.1 1064.7 10027.4 10043.0 10129.0 10213.9 10257.3 1104.1 1107.0 1112.3 1132.6 1133.1 5800.3 5919.8 6207.3 6447.9 6455.8 25748.5 26139.5 26291.7 26430.2 27027.0 26330.9 26757.9 26868.0 26895.2 26978.0 12554.4 13466.1 13499.3 13528.0 13559.9 9565.6 9694.3 9766.5 9771.9 9952.7 22211.3 22363.3 22522.5 22628.2 22912.1 23040.7 23172.7 23254.2 23383.5 23900.8 12326.0 12396.1 12400.0 12463.1 12504.7 14808.1 15085.4 15201.1 15305.1 15344.0 12671.9 12737.0 12774.7 12779.4 13244.1 12336.7 12484.6 12489.8 12572.2 12618.8 11266.1 11320.7 11437.5 11439.1 11453.5 8139.7 8242.8 8305.1 8306.6 8326.5 8497.7 8667.7 8706.3 8717.2 8875.7 11:10:01 INFO - PID 7440 | 11:10:01 INFO - PID 7440 | [#4] prototype.html Cycles:180 Average:5770.98 Median:6374.82 stddev:1593.31 (25.0%) stddev-sans-first:1549.15 11:10:01 INFO - PID 7440 | Values: 566.5 579.7 581.3 607.2 617.8 6351.5 6475.3 6484.4 6488.2 6515.0 6212.9 6340.2 6346.4 6378.0 6388.7 5173.6 5174.7 5188.9 5192.7 5204.8 5076.8 5132.9 5168.3 5188.0 5210.1 5129.2 5139.2 5173.7 5184.9 5206.4 5146.2 5147.5 5149.1 5150.5 5174.5 4708.1 4723.3 4735.0 4739.4 4753.4 6315.4 6343.3 6346.0 6386.0 6386.6 6012.7 6032.5 6052.5 6059.4 6096.6 6710.2 6795.2 6807.7 6813.6 6823.2 5220.9 5239.2 5243.4 5253.0 5260.4 7067.3 7092.2 7127.8 7144.1 7144.2 5373.5 5401.9 5411.4 5415.2 5424.6 5477.6 5505.0 5505.6 5525.3 5533.9 5952.3 6002.7 6021.4 6060.8 6069.8 6519.9 6773.6 6815.8 6828.7 6849.3 1821.7 1828.4 1842.2 1861.0 1869.8 1319.7 1333.2 1339.8 1345.6 1347.3 6224.2 6230.6 6268.8 6347.6 6378.6 6743.9 6790.9 6873.6 6966.3 6974.1 6303.5 6354.7 6366.3 6371.7 6422.4 3582.8 3622.7 3682.7 3686.6 3703.3 7127.5 7176.8 7203.4 7227.8 7268.5 6993.4 7013.5 7039.9 7092.5 7116.7 7059.4 7129.8 7172.0 7222.9 7272.7 6712.8 6831.9 6849.6 6924.9 6987.5 6651.4 6697.5 6699.7 6741.7 6761.9 6981.5 7043.2 7065.7 7108.9 7158.0 5298.8 5317.7 5328.5 5340.7 5347.3 6761.7 6807.8 6823.3 6874.7 7012.8 6406.9 6641.2 6643.9 6724.2 6724.7 6546.5 6575.3 6588.4 6595.4 6632.3 6350.6 6541.2 6561.5 6578.8 6607.0 6701.3 6753.4 6762.2 6773.8 6776.7 6568.4 6619.6 6619.8 6639.2 6647.4 11:10:01 INFO - PID 7440 | 11:10:01 INFO - PID 7440 | [#5] yui.html Cycles:180 Average:29571.66 Median:20034.15 stddev:43246.98 (215.9%) stddev-sans-first:43315.16 11:10:01 INFO - PID 7440 | Values: 1018.2 1023.4 1034.1 1037.9 1049.3 20342.7 20811.2 20852.5 21024.4 21089.6 18814.9 18917.8 18919.8 18974.6 19006.0 1050.7 1062.7 1063.5 1071.9 1073.2 1089.3 1091.2 1096.2 1096.9 1097.6 1048.7 1136.6 1137.0 1140.4 1145.4 1129.4 1129.7 1134.8 1135.4 1141.7 839.2 840.9 842.3 842.8 844.7 20454.3 20773.2 21086.6 21087.1 21905.3 24361.5 24416.5 24764.3 24896.9 24898.9 18851.8 18888.8 19223.0 19233.3 19234.5 3950.1 4007.3 4023.0 4025.0 4031.3 131532.0 158079.2 172000.1 174296.0 174320.0 19712.7 19956.0 20112.3 20113.1 20284.7 36250.8 36265.8 36324.8 36735.3 36810.4 10021.5 10271.8 10339.7 10351.1 10356.7 4891.3 4951.2 4995.0 5007.6 5014.9 6597.2 6604.1 6651.3 6724.3 6768.2 1208.8 1211.0 1212.6 1229.7 1234.0 736.1 757.7 759.7 781.1 787.4 13542.9 13616.3 13752.3 13985.8 14110.5 766.7 778.1 783.9 784.9 790.0 6742.3 6754.2 6772.3 6774.3 6799.9 151762.0 152209.2 155577.0 155814.0 163772.0 131271.0 132927.0 133162.0 139106.0 143389.0 113614.0 114510.0 117597.4 119076.4 120002.4 13808.6 14001.0 14031.4 14113.7 14236.7 57236.7 57242.0 57775.4 58203.4 58454.4 68719.7 69104.7 69184.0 69289.7 69701.0 20600.4 20993.4 21053.5 21091.5 21170.9 23551.5 23687.4 23932.6 24298.0 24311.4 19888.0 20380.8 20445.7 20451.1 20567.6 20513.8 20841.0 20850.5 20887.6 20888.9 19789.7 19850.8 19896.1 20123.7 20375.5 14722.6 14832.0 14967.6 14970.8 15004.1 14689.9 15150.8 15264.7 15280.8 15359.6 11:10:01 INFO - PID 7440 | -------- Summary: end -------- 11:10:01 INFO - PID 7440 | 11:10:02 INFO - TEST-INFO | 7440: exit 0 11:10:02 INFO - TEST-OK | dromaeo_css | took 1107015ms 11:10:02 INFO - TEST-START | kraken 11:10:02 INFO - Initialising browser for kraken test... 11:10:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38178/getInfo.html -profile /tmp/tmpCJb2pT/profile 11:10:02 INFO - TEST-INFO | started process 7807 (/builds/slave/test/build/application/firefox/firefox http://localhost:38178/getInfo.html) 11:10:06 INFO - TEST-INFO | 7807: exit 0 11:10:07 INFO - Browser initialized. 11:10:07 INFO - Running cycle 1/1 for kraken test... 11:10:07 INFO - Using env: {'DISPLAY': ':0', 11:10:07 INFO - 'HOME': '/home/cltbld', 11:10:07 INFO - 'JSGC_DISABLE_POISONING': '1', 11:10:07 INFO - 'LANG': 'en_US.UTF-8', 11:10:07 INFO - 'LANGUAGE': 'en_US:en', 11:10:07 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:10:07 INFO - 'LOGNAME': 'cltbld', 11:10:07 INFO - 'MAIL': '/var/mail/cltbld', 11:10:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:10:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:10:07 INFO - 'MOZ_CRASHREPORTER': '1', 11:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:07 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:10:07 INFO - 'MOZ_NO_REMOTE': '1', 11:10:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:10:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:10:07 INFO - 'NO_EM_RESTART': '1', 11:10:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:07 INFO - 'PWD': '/builds/slave/test', 11:10:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:10:07 INFO - 'RUST_BACKTRACE': 'full', 11:10:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:10:07 INFO - 'SHELL': '/bin/bash', 11:10:07 INFO - 'SHLVL': '1', 11:10:07 INFO - 'TERM': 'linux', 11:10:07 INFO - 'TMOUT': '86400', 11:10:07 INFO - 'USER': 'cltbld', 11:10:07 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901', 11:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:07 INFO - '_': '/tools/buildbot/bin/python'} 11:10:07 INFO - TEST-INFO | started process 7991 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCJb2pT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:10:07 INFO - PID 7991 | 11:10:07 INFO - PID 7991 | (/builds/slave/test/build/application/firefox/firefox:8039): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:10:07 INFO - PID 7991 | 11:10:18 INFO - PID 7991 | RSS: Main: 142868480 11:10:18 INFO - PID 7991 | 11:10:20 INFO - PID 7991 | Cycle 1(1): loaded http://localhost:38178/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38178/tests/kraken/driver.html?testName=audio-beat-detection) 11:10:21 INFO - PID 7991 | RSS: Main: 149467136 11:10:21 INFO - PID 7991 | 11:10:24 INFO - PID 7991 | Cycle 1(1): loaded http://localhost:38178/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38178/tests/kraken/driver.html?testName=audio-dft) 11:10:24 INFO - PID 7991 | RSS: Main: 150585344 11:10:24 INFO - PID 7991 | 11:10:27 INFO - PID 7991 | Cycle 1(1): loaded http://localhost:38178/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38178/tests/kraken/driver.html?testName=audio-fft) 11:10:28 INFO - PID 7991 | RSS: Main: 152088576 11:10:28 INFO - PID 7991 | 11:10:30 INFO - PID 7991 | Cycle 1(1): loaded http://localhost:38178/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38178/tests/kraken/driver.html?testName=audio-oscillator) 11:10:30 INFO - PID 7991 | RSS: Main: 151797760 11:10:30 INFO - PID 7991 | 11:10:32 INFO - PID 7991 | Cycle 1(1): loaded http://localhost:38178/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38178/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:10:33 INFO - PID 7991 | RSS: Main: 152285184 11:10:33 INFO - PID 7991 | 11:10:36 INFO - PID 7991 | Cycle 1(1): loaded http://localhost:38178/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38178/tests/kraken/driver.html?testName=imaging-darkroom) 11:10:37 INFO - PID 7991 | RSS: Main: 151609344 11:10:37 INFO - PID 7991 | 11:10:40 INFO - PID 7991 | Cycle 1(1): loaded http://localhost:38178/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38178/tests/kraken/driver.html?testName=imaging-desaturate) 11:10:41 INFO - PID 7991 | RSS: Main: 151064576 11:10:41 INFO - PID 7991 | 11:10:44 INFO - PID 7991 | Cycle 1(1): loaded http://localhost:38178/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38178/tests/kraken/driver.html?testName=json-parse-financial) 11:10:44 INFO - PID 7991 | RSS: Main: 152674304 11:10:44 INFO - PID 7991 | 11:10:46 INFO - PID 7991 | Cycle 1(1): loaded http://localhost:38178/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38178/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:10:46 INFO - PID 7991 | RSS: Main: 152510464 11:10:46 INFO - PID 7991 | 11:10:48 INFO - PID 7991 | Cycle 1(1): loaded http://localhost:38178/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38178/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:10:48 INFO - PID 7991 | RSS: Main: 152707072 11:10:48 INFO - PID 7991 | 11:10:50 INFO - PID 7991 | Cycle 1(1): loaded http://localhost:38178/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38178/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:10:51 INFO - PID 7991 | RSS: Main: 149729280 11:10:51 INFO - PID 7991 | 11:10:53 INFO - PID 7991 | Cycle 1(1): loaded http://localhost:38178/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38178/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:10:54 INFO - PID 7991 | RSS: Main: 149938176 11:10:54 INFO - PID 7991 | 11:10:57 INFO - PID 7991 | Cycle 1(1): loaded http://localhost:38178/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38178/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:10:57 INFO - PID 7991 | RSS: Main: 150364160 11:10:57 INFO - PID 7991 | 11:10:59 INFO - PID 7991 | Cycle 1(1): loaded http://localhost:38178/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38178/tests/kraken/driver.html?testName=ai-astar) 11:10:59 INFO - PID 7991 | RSS: Main: 151891968 11:10:59 INFO - PID 7991 | 11:10:59 INFO - PID 7991 | __start_tp_report 11:10:59 INFO - PID 7991 | _x_x_mozilla_page_load 11:10:59 INFO - PID 7991 | _x_x_mozilla_page_load_details 11:10:59 INFO - PID 7991 | |i|pagename|runs| 11:10:59 INFO - PID 7991 | |0;ai-astar;106;107;104;107;106;105;106;104;105;107 11:10:59 INFO - PID 7991 | |1;audio-beat-detection;111;117;110;112;112;116;110;115;112;111 11:10:59 INFO - PID 7991 | |2;audio-dft;163;161;162;160;162;160;163;162;161;164 11:10:59 INFO - PID 7991 | |3;audio-fft;74;72;74;75;73;73;73;73;73;75 11:10:59 INFO - PID 7991 | |4;audio-oscillator;83;77;76;77;76;78;78;78;78;78 11:10:59 INFO - PID 7991 | |5;imaging-gaussian-blur;114;114;111;114;114;114;114;109;113;111 11:10:59 INFO - PID 7991 | |6;imaging-darkroom;147;148;157;148;148;148;149;149;148;148 11:10:59 INFO - PID 7991 | |7;imaging-desaturate;94;94;94;94;94;94;94;95;95;94 11:10:59 INFO - PID 7991 | |8;json-parse-financial;57;45;41;42;43;44;47;44;44;42 11:10:59 INFO - PID 7991 | |9;json-stringify-tinderbox;39;38;37;37;38;39;38;38;38;38 11:10:59 INFO - PID 7991 | |10;stanford-crypto-aes;74;72;70;69;70;68;71;69;70;72 11:10:59 INFO - PID 7991 | |11;stanford-crypto-ccm;113;102;105;106;106;103;103;108;102;105 11:10:59 INFO - PID 7991 | |12;stanford-crypto-pbkdf2;156;152;157;150;151;152;153;148;158;147 11:10:59 INFO - PID 7991 | |13;stanford-crypto-sha256-iterative;58;58;57;56;59;59;60;57;60;57 11:10:59 INFO - PID 7991 | __end_tp_report 11:10:59 INFO - PID 7991 | __start_cc_report 11:10:59 INFO - PID 7991 | _x_x_mozilla_cycle_collect,681 11:10:59 INFO - PID 7991 | __end_cc_report 11:10:59 INFO - PID 7991 | __startTimestamp1506967859705__endTimestamp 11:10:59 INFO - PID 7991 | 11:10:59 INFO - PID 7991 | ------- Summary: start ------- 11:10:59 INFO - PID 7991 | Number of tests: 14 11:10:59 INFO - PID 7991 | 11:10:59 INFO - PID 7991 | [#0] ai-astar Cycles:10 Average:105.70 Median:106.00 stddev:1.16 (1.1%) stddev-sans-first:1.22 11:10:59 INFO - PID 7991 | Values: 106.0 107.0 104.0 107.0 106.0 105.0 106.0 104.0 105.0 107.0 11:10:59 INFO - PID 7991 | 11:10:59 INFO - PID 7991 | [#1] audio-beat-detection Cycles:10 Average:112.60 Median:112.00 stddev:2.50 (2.2%) stddev-sans-first:2.59 11:10:59 INFO - PID 7991 | Values: 111.0 117.0 110.0 112.0 112.0 116.0 110.0 115.0 112.0 111.0 11:10:59 INFO - PID 7991 | 11:10:59 INFO - PID 7991 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:1.32 (0.8%) stddev-sans-first:1.32 11:10:59 INFO - PID 7991 | Values: 163.0 161.0 162.0 160.0 162.0 160.0 163.0 162.0 161.0 164.0 11:10:59 INFO - PID 7991 | 11:10:59 INFO - PID 7991 | [#3] audio-fft Cycles:10 Average:73.50 Median:73.50 stddev:0.97 (1.3%) stddev-sans-first:1.01 11:10:59 INFO - PID 7991 | Values: 74.0 72.0 74.0 75.0 73.0 73.0 73.0 73.0 73.0 75.0 11:10:59 INFO - PID 7991 | 11:10:59 INFO - PID 7991 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:78.00 stddev:1.97 (2.5%) stddev-sans-first:0.87 11:10:59 INFO - PID 7991 | Values: 83.0 77.0 76.0 77.0 76.0 78.0 78.0 78.0 78.0 78.0 11:10:59 INFO - PID 7991 | 11:10:59 INFO - PID 7991 | [#5] imaging-gaussian-blur Cycles:10 Average:112.80 Median:114.00 stddev:1.81 (1.6%) stddev-sans-first:1.87 11:10:59 INFO - PID 7991 | Values: 114.0 114.0 111.0 114.0 114.0 114.0 114.0 109.0 113.0 111.0 11:10:59 INFO - PID 7991 | 11:10:59 INFO - PID 7991 | [#6] imaging-darkroom Cycles:10 Average:149.00 Median:148.00 stddev:2.87 (1.9%) stddev-sans-first:2.95 11:10:59 INFO - PID 7991 | Values: 147.0 148.0 157.0 148.0 148.0 148.0 149.0 149.0 148.0 148.0 11:10:59 INFO - PID 7991 | 11:10:59 INFO - PID 7991 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 11:10:59 INFO - PID 7991 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 94.0 95.0 95.0 94.0 11:10:59 INFO - PID 7991 | 11:10:59 INFO - PID 7991 | [#8] json-parse-financial Cycles:10 Average:44.90 Median:44.00 stddev:4.58 (10.4%) stddev-sans-first:1.81 11:10:59 INFO - PID 7991 | Values: 57.0 45.0 41.0 42.0 43.0 44.0 47.0 44.0 44.0 42.0 11:10:59 INFO - PID 7991 | 11:10:59 INFO - PID 7991 | [#9] json-stringify-tinderbox Cycles:10 Average:38.00 Median:38.00 stddev:0.67 (1.8%) stddev-sans-first:0.60 11:10:59 INFO - PID 7991 | Values: 39.0 38.0 37.0 37.0 38.0 39.0 38.0 38.0 38.0 38.0 11:10:59 INFO - PID 7991 | 11:10:59 INFO - PID 7991 | [#10] stanford-crypto-aes Cycles:10 Average:70.50 Median:70.50 stddev:1.78 (2.5%) stddev-sans-first:1.36 11:10:59 INFO - PID 7991 | Values: 74.0 72.0 70.0 69.0 70.0 68.0 71.0 69.0 70.0 72.0 11:10:59 INFO - PID 7991 | 11:10:59 INFO - PID 7991 | [#11] stanford-crypto-ccm Cycles:10 Average:105.30 Median:105.50 stddev:3.33 (3.2%) stddev-sans-first:2.07 11:10:59 INFO - PID 7991 | Values: 113.0 102.0 105.0 106.0 106.0 103.0 103.0 108.0 102.0 105.0 11:10:59 INFO - PID 7991 | 11:10:59 INFO - PID 7991 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.40 Median:152.50 stddev:3.69 (2.4%) stddev-sans-first:3.67 11:10:59 INFO - PID 7991 | Values: 156.0 152.0 157.0 150.0 151.0 152.0 153.0 148.0 158.0 147.0 11:10:59 INFO - PID 7991 | 11:10:59 INFO - PID 7991 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:58.10 Median:58.50 stddev:1.37 (2.3%) stddev-sans-first:1.45 11:10:59 INFO - PID 7991 | Values: 58.0 58.0 57.0 56.0 59.0 59.0 60.0 57.0 60.0 57.0 11:10:59 INFO - PID 7991 | -------- Summary: end -------- 11:10:59 INFO - PID 7991 | 11:11:00 INFO - PID 7991 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:11:00 INFO - TEST-INFO | 7991: exit 0 11:11:00 INFO - TEST-OK | kraken | took 58284ms 11:11:00 INFO - SUITE-END | took 1165s 11:11:00 INFO - Completed test suite (00:19:25) 11:11:00 INFO - javascript benchmark 11:11:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9498.944877474158, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [4015.257177422177, 4021.15555733296, 4024.0, 4035.939460908087, 4046.6155715207046, 6535.575187612813, 6562.639054851981, 6567.9671601641985, 6607.933920660792, 6632.2704502504685, 6486.286508484063, 6522.641254730988, 6546.214454265495, 6620.304867988864, 6630.436412904897, 5856.707164641773, 5920.615160014607, 5940.910886336697, 5974.790882319098, 5984.491318237955, 5862.9413705863035, 5934.643921364732, 5985.982382994901, 6019.578629495937, 6036.305824830135, 5983.341832398432, 6009.1286763419275, 6020.337762846083, 6023.759049638009, 6053.061775424816, 5834.124881267844, 5902.4097590240635, 5933.406659334075, 5956.731947062371, 6031.788887388942, 5779.682117483537, 5788.507976821975, 5801.854953626151, 5822.243108395882, 5909.586328956974, 6446.516511261674, 6460.741570337181, 6484.546081774278, 6524.510661700344, 6557.180352455943, 7048.083749112634, 7062.364387205176, 7132.215456299803, 7146.892796608055, 7153.642317884085, 7011.228764835864, 7027.613481258529, 7076.080109585772, 7085.185203701589, 7096.396806271473, 6463.5798673086565, 6558.606483610999, 6567.868642627169, 6584.111144995412, 6638.6680665966505, 7241.8551628967125, 7271.63641817907, 7313.378362839218, 7329.670164842595, 7336.2296958819625, 6937.202221744463, 6959.095317608679, 6963.6866341014775, 7047.577145371294, 7081.5042946994245, 6925.826854328682, 7080.610566418896, 7106.822329441805, 7113.928860711327, 7116.71533138679, 6946.131733533309, 6999.475039372068, 7016.92983070173, 7070.611116388648, 7130.964345178241, 7000.26497217795, 7078.681459334342, 7102.076730025064, 7110.7511237106455, 7127.358537731629, 5224.791008359699, 5279.52484276417, 5303.003802851376, 5305.946940530623, 5347.064263753828, 4770.499097594772, 4785.569298763128, 4855.878603034952, 4869.050535145612, 4878.195097808893, 6564.081028655992, 6578.5395022347975, 6656.534042617067, 6659.86680266392, 6671.566348187353, 7097.432205423554, 7110.146782386147, 7121.109861267341, 7177.246389129171, 7184.389326907167, 6499.5775274607, 6504.642244676588, 6549.377809108127, 6592.9340706593275, 6629.767958121539, 5931.525477961753, 5931.881362372728, 5967.5524335675, 5971.552133589998, 5980.132880732356, 7157.355837974607, 7240.855182896313, 7245.601491917995, 7265.491415600857, 7385.261473852572, 7018.227994920657, 7031.859362812817, 7051.365377116084, 7081.150261968597, 7084.25615310385, 7216.71133154689, 7225.855482890208, 7226.060612120391, 7233.348998590152, 7297.051383320135, 7072.292770722886, 7098.574085554883, 7123.430125589836, 7125.0, 7130.144382674112, 7033.120859892513, 7144.857102858018, 7146.071010768567, 7227.638618069181, 7229.240929702306, 6766.289539598271, 6850.554713943578, 6881.862362752817, 6953.304669533006, 6977.162740471176, 6796.830079248059, 6881.41507971808, 6884.4148247399535, 6887.0, 6892.138482689664, 6823.761168359084, 6875.89686154698, 6904.171499420102, 6908.3091690830515, 6912.930870691229, 6857.897131542931, 6946.270641582763, 7005.929940700732, 7012.368886800213, 7022.754203602849, 6591.143151390289, 6682.431993280626, 6705.8994115087335, 6706.899396509154, 6716.966415167893], "value": 6498.507533277616, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [992.7815880506289, 1018.9949050254747, 1021.5964693945891, 1021.6475316015972, 1025.9384436933788, 47211.527884721145, 48348.0, 48439.51560484394, 48466.0, 48549.75725121374, 43879.561204387945, 43967.78016109915, 43976.56023439765, 44011.77994110033, 44357.0, 7966.641501132449, 8107.0, 8113.107558168596, 8118.553479558622, 8133.75598732039, 7960.164182760814, 8006.799830004267, 8022.839543209161, 8034.276915077642, 8044.758657240262, 7983.0, 8746.562671866413, 8897.243734282594, 8904.51025193614, 8905.955470222672, 8779.780505487382, 8803.427777194525, 8838.248748856322, 8838.248748856355, 8855.601497932545, 8708.521031343274, 8832.160944710242, 8841.248493878029, 8845.911540884574, 8852.291816654651, 42111.7894410526, 42161.78919105416, 42328.57671423308, 42372.57627423717, 42480.3627945581, 3802.90492737681, 3820.522434695663, 3828.3491806392703, 3829.7893615851117, 3838.865639702597, 35912.28175436477, 35953.28093438143, 36004.63995360065, 36040.09899752501, 36119.09702257438, 4728.645351598644, 4777.211760059586, 4820.325154478376, 4847.175980083394, 4878.0975519529, 232236.0, 232690.0, 233013.0, 233709.0, 234658.0, 50636.0, 50766.49233507692, 55980.440195598334, 56473.7176314124, 57526.71236643874, 59620.70189649024, 59721.0, 59738.0, 59907.40092599018, 59978.70010650006, 19064.33274835374, 19092.71360929587, 19095.0, 19146.138423770964, 19232.134553945107, 15659.843401566066, 15767.290471928562, 15770.684586308209, 15982.68034639324, 16073.115978621054, 15714.68570628581, 15832.129232892072, 15858.127802970947, 15967.962082464603, 15995.80031497619, 6121.490189022568, 6131.172291740647, 6203.131561581382, 6250.781222657185, 6273.215848018997, 1117.9944100279447, 1120.9439528023565, 1126.515598292742, 1133.0595605647459, 1135.1259530161728, 26524.336891577477, 26645.73354266471, 26679.733202668114, 27061.458770824473, 27115.0, 1237.0845574274972, 1238.8946939510063, 1244.166408506303, 1250.0874361715998, 1254.5609036837034, 13822.0, 13971.720565588834, 13992.30038498071, 14013.579592612237, 14322.785158222636, 295278.0, 297779.0, 298156.0, 298726.0, 300037.0, 62591.374086259464, 62654.43363915847, 62890.37109628937, 63047.684761575896, 63348.366516334245, 1558.345494892148, 1581.7231984402683, 1583.1767480909862, 1596.6327744618802, 1604.5982517063899, 26011.219663410127, 26146.215613531625, 26382.736172638797, 26440.8677956609, 26534.336641583344, 44103.117937640425, 44679.776601116784, 44723.0, 44737.776311118236, 44760.77619611881, 243217.0, 244029.0, 246860.0, 246906.0, 246908.0, 38089.42865856959, 39101.6089839098, 41285.0, 41325.1734965305, 41392.0, 53258.46741532535, 54455.45544544504, 55015.17477237764, 55087.0, 55202.171967421324, 39519.2096158081, 39922.201555969295, 40015.0, 40035.0, 40586.59413405828, 38620.22759544737, 38836.41745373765, 39024.41463377995, 39506.60493395029, 39831.0, 45460.318095227936, 45683.54316456926, 45743.54256457526, 45906.0, 46043.0, 37118.8144059278, 37264.813675931444, 37526.43710344292, 37859.62140378561, 38178.809105955406, 25870.0, 25985.0, 26071.217863464128, 26366.472670546096, 26445.867770661025], "value": 19727.08807502711, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.7946667333118, 636.9108324834527, 643.6073994863133, 645.519088279232, 654.0810161722779, 23243.651345229835, 23591.528169436602, 24329.75670243297, 24333.878330608346, 24417.877910610423, 26541.0, 27635.447291054166, 27653.585196222073, 27656.032038878642, 27665.170044898634, 6624.13886194794, 6835.111435513389, 6911.03245545624, 6942.166939967211, 6945.270746571613, 6817.897731534031, 6823.420009299216, 6832.453403727714, 6840.692168852389, 6846.5549739267035, 6746.595204287734, 6771.0, 6786.524943253974, 6790.083338749255, 6791.422729068036, 6694.698738556753, 6751.122354093985, 6765.492588055916, 6765.695543700521, 6771.72913083476, 6329.8417539561415, 6349.904751428732, 6365.586236894587, 6376.872462550769, 6391.87216255677, 29759.0, 30491.085267442013, 30750.0, 30830.075097747103, 30849.69150308513, 2292.1289909834322, 2312.7571604981404, 2327.2203811723093, 2331.25399872041, 2340.73081595617, 20348.084336204905, 20957.476063098544, 21020.054097565648, 21043.263485777923, 21063.262785802424, 2530.7595778401023, 2624.9212523624324, 2628.080171939825, 2629.368951451657, 2638.0503018913178, 144465.277673612, 150309.0, 163969.0, 171112.0, 171465.14267428708, 15956.76064859028, 16883.24025418859, 17057.402990895258, 17066.743998840026, 17114.14429278531, 27085.593716094274, 27112.186634400998, 27139.0, 27141.86429067842, 27174.048908288114, 11194.776104477865, 11241.83137252942, 11330.37682927447, 11360.829587556193, 11367.261128026817, 10080.3951762893, 10083.647072352433, 10246.795064098826, 10270.281080324456, 10363.533640986174, 9954.950225248827, 9959.502024898726, 10033.147182489423, 10105.595776169019, 10261.333013354108, 2835.702251263629, 2888.797784155089, 2913.920511284219, 2928.1215635309322, 2933.8386388748822, 1048.5229220704562, 1066.2003497376968, 1069.9679009629724, 1072.983905241422, 1076.5908954597203, 20898.37304880856, 21299.799577162903, 21668.34994950154, 21670.34988950334, 21821.23625673094, 1103.5806393570392, 1122.8428020077197, 1131.298594871185, 1140.3158105136852, 1146.0430540498776, 11522.654320370402, 11626.53493860253, 11721.062315014779, 11855.703607409883, 11858.0, 697569.0, 703345.0, 711268.0, 727582.0, 736257.0, 138982.0, 139218.0, 141124.0, 144798.2760086193, 145215.0, 1113.716002419378, 1138.3533285112148, 1179.2983175010854, 1262.7158889249845, 1271.1356277731047, 21270.149194032067, 21689.240876569245, 21713.457163571038, 21727.782722172575, 21840.67238991448, 65349.01976470452, 66046.33953660402, 66995.0, 67388.6630566844, 68694.0, 82997.58501207456, 83553.58223208845, 84194.0, 84547.5772621133, 84570.0, 15605.297761600528, 16039.599010024376, 16118.838811611733, 16133.274002670143, 16156.757648635516, 23539.411514711584, 23960.682162480534, 23990.28029159128, 23995.760042400052, 24042.87978560166, 19458.610827783083, 19624.313149039714, 19844.50388740293, 19932.20271189136, 20008.099635516144, 16439.0, 16460.259288331814, 16676.916615416845, 16954.06752628568, 17041.318347266468, 19534.804651953298, 19932.70100948458, 20228.79771202328, 20275.59448810986, 20490.38528844137, 15602.84397156014, 15756.60608484797, 15831.841681583037, 15920.681586368439, 15931.805114616218, 19574.706379404037, 19616.50958725986, 19657.41027769169, 19868.304609338604, 19877.204911803365], "value": 13156.126725387421, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1487.7693957436595, 1531.594127556197, 1538.6845696632186, 1549.8682611977981, 1550.2093932094642, 17428.39000634978, 17982.5504362391, 18086.186121624527, 18104.366347177853, 18175.0, 17345.0, 17388.391406300783, 17433.564160895985, 17472.475825725218, 17508.47474575765, 3159.5418664293666, 3211.710946014858, 3256.560364350806, 3272.247383101888, 3276.1973316537483, 3079.7382222511, 3129.0, 3132.7963682360687, 3141.3403185330994, 3152.984235078833, 3004.1137864329967, 3018.9396212075744, 3151.543026261191, 3264.5919260092487, 3265.9020229393036, 3076.1694342527617, 3092.041467145181, 3120.60992375953, 3121.6410112836975, 3121.797083189608, 2944.263934016496, 2952.2766922104006, 2952.970470295291, 2961.940761184764, 2965.4810408178696, 16784.496465106065, 17198.74201886973, 17228.827711722974, 17258.13709314542, 17310.56723581908, 9226.44641321516, 9509.476978766164, 9573.808523829553, 9610.135087842127, 9610.855837162448, 5918.201042859202, 5944.851378715523, 6400.4559612432995, 6404.519661025395, 6467.417932386108, 2000.8599398042143, 2009.6282187795305, 2033.593281343737, 2078.7089807426973, 2081.7814129516332, 31416.0, 31607.84196079028, 31641.52537711936, 31673.52489712656, 31690.0, 7922.603869806487, 7972.402069844785, 8025.839483210303, 8191.139930307351, 8204.958975205085, 20350.694739578918, 20379.286724964553, 20461.488462788548, 20486.282980095624, 20510.487237818874, 8916.777080573038, 9038.367314288069, 9056.864147037799, 9060.54697265134, 9073.54632268384, 8114.43198976066, 8223.712170074019, 8305.916940830515, 8308.210719981713, 8449.493030418194, 6005.129256257818, 6147.784827531015, 6191.1641928339095, 6217.067439883963, 6235.407636274546, 1711.3411336635236, 1763.1096296370251, 1795.9615836026205, 1799.0554958646815, 1800.7118860982441, 851.2211326636059, 1025.5180065369264, 1037.2531777120462, 1042.0881728487573, 1064.723171975292, 10027.398356098658, 10043.0, 10129.037741414553, 10213.897861021444, 10257.33327333721, 1104.1222228328497, 1106.9667909962714, 1112.2881355932209, 1132.5809450503366, 1133.0935251798528, 5800.332961709373, 5919.763209471573, 6207.286162091327, 6447.903281450782, 6455.7740479083, 25748.48503029929, 26139.477210455683, 26291.73708262931, 26430.207093787216, 27027.0, 26330.893528517674, 26757.86621066921, 26868.0, 26895.193144205707, 26978.0, 12554.372281386077, 13466.124701894541, 13499.257540835151, 13528.0, 13559.932200338935, 9565.61737530491, 9694.272929530172, 9766.511674416393, 9771.902280977383, 9952.651657191964, 22211.333659990225, 22363.329100127023, 22522.5495490086, 22628.208012720133, 22912.083516659146, 23040.651461247297, 23172.652410214196, 23254.186103486296, 23383.532329353657, 23900.760992390547, 12326.0, 12396.132270740962, 12400.0, 12463.065270104596, 12504.749905001667, 14808.111513309235, 15085.396584136512, 15201.087934723952, 15305.08169509833, 15344.0, 12671.936640316739, 12737.0, 12774.74450510966, 12779.424925878544, 13244.072914895865, 12336.683667867557, 12484.625461236177, 12489.812652810397, 12572.245665260114, 12618.810717839056, 11266.098712102848, 11320.660380188607, 11437.485313161094, 11439.142064345373, 11453.541858325907, 8139.674413023649, 8242.835143297221, 8305.127961563949, 8306.552780596227, 8326.542040187605, 8497.74506764798, 8667.696630617898, 8706.347023973101, 8717.171868672465, 8875.689350872946], "value": 7749.302102259309, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.5071387892536, 579.6840721806615, 581.3198557687505, 607.2379164148992, 617.8486270863638, 6351.460125889299, 6475.287718350977, 6484.4488218501365, 6488.156539649851, 6515.0, 6212.868442485753, 6340.23917129945, 6346.397092276226, 6377.968110159454, 6388.680565971706, 5173.586113110942, 5174.689518628876, 5188.948110518903, 5192.688438693672, 5204.817831375903, 5076.822311219108, 5132.871678208037, 5168.2764412982215, 5188.0, 5210.114280572293, 5129.1536896412235, 5139.203423469368, 5173.715445650488, 5184.896302073957, 5206.427292997767, 5146.228065790143, 5147.459516750725, 5149.124648809711, 5150.510701483353, 5174.4825517448335, 4708.128996135726, 4723.315119307716, 4735.0, 4739.431268247798, 4753.429588449409, 6315.431611154972, 6343.302236753953, 6346.0, 6385.96807015962, 6386.616802991835, 6012.669303188323, 6032.517398608101, 6052.455279024909, 6059.36376680451, 6096.603720758181, 6710.228323742784, 6795.18457785064, 6807.727690892359, 6813.557118787313, 6823.215330237037, 5220.895582088375, 5239.240310155006, 5243.397009343936, 5253.028189784902, 5260.421353651095, 7067.257937916547, 7092.219855815856, 7127.8218044548785, 7144.142702875636, 7144.178419481774, 5373.543248823894, 5401.891962160735, 5411.377691565442, 5415.187721841677, 5424.593155513273, 5477.589180811455, 5505.03661859173, 5505.5775458798, 5525.309336332958, 5533.861653458615, 5952.345242023374, 6002.729877155539, 6021.3677563856045, 6060.787872424532, 6069.817905462844, 6519.934800652028, 6773.593584384953, 6815.795526134224, 6828.658567071627, 6849.2808255132495, 1821.6629923463936, 1828.44232509086, 1842.1894366478707, 1861.0415635947497, 1869.7569315988835, 1319.7228581997888, 1333.1867560787912, 1339.8325209348832, 1345.569417786299, 1347.2724728646617, 6224.221972253468, 6230.563860529719, 6268.780592679325, 6347.587406818542, 6378.649174295459, 6743.9325606743305, 6790.93209067903, 6873.5875847449315, 6966.303369662993, 6974.128233970754, 6303.495720342362, 6354.650494222862, 6366.331535188831, 6371.681415929185, 6422.42198202164, 3582.811297944831, 3622.728295377857, 3682.668559829628, 3686.562240726119, 3703.333399987974, 7127.469748239404, 7176.820579485555, 7203.423726101899, 7227.783636281909, 7268.491205615662, 6993.440524758007, 7013.509054366247, 7039.894401583981, 7092.503524753321, 7116.679749411289, 7059.364657180879, 7129.82175445618, 7171.960065790536, 7222.89165662526, 7272.745453909088, 6712.83217919556, 6831.89752153728, 6849.589024658537, 6924.896126558207, 6987.545809522365, 6651.43462805667, 6697.531172817856, 6699.698513566804, 6741.662916854236, 6761.864762704817, 6981.5461994970165, 7043.225245222919, 7065.682044307916, 7108.898120791286, 7158.0, 5298.841034768963, 5317.6543524672325, 5328.4671532846405, 5340.679559226459, 5347.288867562343, 6761.695723692347, 6807.795766127024, 6823.317668233335, 6874.6562671867205, 7012.824679383056, 6406.935930640634, 6641.203055633355, 6643.867122657616, 6724.159480064992, 6724.663766811737, 6546.509011824037, 6575.30959249291, 6588.40704336612, 6595.373439523238, 6632.336766323329, 6350.618962862243, 6541.247756508063, 6561.507886908405, 6578.76974305897, 6607.0, 6701.262861085374, 6753.425958793558, 6762.154730658668, 6773.796786096425, 6776.728930842711, 6568.408843204134, 6619.569727967667, 6619.801405957829, 6639.203295604559, 6647.401733843977], "value": 5315.479495623553, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1018.1854516386893, 1023.4268809466699, 1034.1261633919337, 1037.937723736576, 1049.3074570783285, 20342.6948595771, 20811.167553297888, 20852.478688032807, 21024.369268921917, 21089.57820843582, 18814.905925470386, 18917.810821891777, 18919.810801891977, 18974.62050758984, 19006.0, 1050.6585359758071, 1062.7236918401213, 1063.462951209638, 1071.8874518175596, 1073.2433634287809, 1089.2810744908363, 1091.2088735666619, 1096.2162054131295, 1096.8628921384827, 1097.5664612478067, 1048.6539441984164, 1136.6192325570903, 1136.9545218191263, 1140.401289323108, 1145.37004647444, 1129.3675541696675, 1129.7232178116415, 1134.7900638381884, 1135.4152611405134, 1141.737400397909, 839.17760594617, 840.9495430274142, 842.3177226446597, 842.7640260726945, 844.7339088187201, 20454.250227324876, 20773.16907323705, 21086.5782684347, 21087.051082701317, 21905.342839714835, 24361.512769744684, 24416.51166976668, 24764.25707228786, 24896.875515622487, 24898.87550562254, 18851.811481885143, 18888.81111188884, 19223.0, 19233.326833560757, 19234.519137021547, 3950.1309711863346, 4007.338789099795, 4022.9798851005557, 4025.0, 4031.294523458383, 131532.0, 158079.2096039524, 172000.1399992992, 174295.99999999872, 174320.0, 19712.70430943537, 19956.0, 20112.296069637494, 20113.09491072905, 20284.695729564068, 36250.8187459061, 36265.81867090701, 36324.81837590848, 36735.265294694495, 36810.447843282374, 10021.498925053718, 10271.767387913349, 10339.689809305733, 10351.06840384369, 10356.689299321033, 4891.315215869781, 4951.207806750903, 4995.0, 5007.624428167902, 5014.949850501521, 6597.208334999831, 6604.14146160996, 6651.33486651331, 6724.293949135368, 6768.187817461936, 1208.788462019143, 1211.0, 1212.6240865331774, 1229.7048708309942, 1234.0312854409246, 736.1313649893177, 757.6969212315119, 759.6847308367077, 781.0861292288037, 787.4251796188814, 13542.864571354357, 13616.319184040758, 13752.31238438074, 13985.79021314681, 14110.506132285525, 766.7009866152205, 778.1207235823483, 783.8824176373589, 784.9136594974548, 789.9565523896126, 6742.258351581322, 6754.189497260262, 6772.322767723188, 6774.288699686561, 6799.93200067993, 151762.0, 152209.23895380675, 155577.0, 155814.0, 163772.0, 131271.0, 132927.0, 133162.0, 139106.0, 143389.0, 113614.0, 114510.0, 117597.41201293939, 119076.40461797809, 120002.39998800124, 13808.585742427744, 14001.0, 14031.36858841375, 14113.717725645225, 14236.715265694835, 57236.71381643065, 57242.0, 57775.422245777, 58203.4179658198, 58454.41545584489, 68719.65640171967, 69104.6544767273, 69184.0, 69289.65355173394, 69701.0, 20600.38198854037, 20993.37019889406, 21053.473663157933, 21091.472713182295, 21170.9414529276, 23551.52896942086, 23687.407814804767, 23932.641010385207, 24298.0, 24311.392215194763, 19888.0, 20380.79619203789, 20445.693314599994, 20451.079701413768, 20567.58864822665, 20513.794862051785, 20841.0, 20850.478738031063, 20887.58224835525, 20888.895555522126, 19789.703154452982, 19850.801491984894, 19896.104675289356, 20123.698144527552, 20375.4906127348, 14722.55832325032, 14832.0, 14967.550973470814, 14970.775438368652, 15004.099754014795, 14689.853101468849, 15150.848491514944, 15264.694706105594, 15280.847191527942, 15359.61600959985], "value": 10573.436415701834, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1356.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 107.0, 104.0, 107.0, 106.0, 105.0, 106.0, 104.0, 105.0, 107.0], "value": 105.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 117.0, 110.0, 112.0, 112.0, 116.0, 110.0, 115.0, 112.0, 111.0], "value": 112.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 162.0, 160.0, 162.0, 160.0, 163.0, 162.0, 161.0, 164.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 72.0, 74.0, 75.0, 73.0, 73.0, 73.0, 73.0, 73.0, 75.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 77.0, 76.0, 77.0, 76.0, 78.0, 78.0, 78.0, 78.0, 78.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 111.0, 114.0, 114.0, 114.0, 114.0, 109.0, 113.0, 111.0], "value": 112.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 157.0, 148.0, 148.0, 148.0, 149.0, 149.0, 148.0, 148.0], "value": 149.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 45.0, 41.0, 42.0, 43.0, 44.0, 47.0, 44.0, 44.0, 42.0], "value": 44.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 38.0, 37.0, 37.0, 38.0, 39.0, 38.0, 38.0, 38.0, 38.0], "value": 38.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 72.0, 70.0, 69.0, 70.0, 68.0, 71.0, 69.0, 70.0, 72.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 102.0, 105.0, 106.0, 106.0, 103.0, 103.0, 108.0, 102.0, 105.0], "value": 105.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 152.0, 157.0, 150.0, 151.0, 152.0, 153.0, 148.0, 158.0, 147.0], "value": 152.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 57.0, 56.0, 59.0, 59.0, 60.0, 57.0, 60.0, 57.0], "value": 58.1, "unit": "score"}]}]} 11:11:00 INFO - Return code: 0 11:11:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:11:00 INFO - # TBPL SUCCESS # 11:11:00 INFO - Running post-action listener: _package_coverage_data 11:11:00 INFO - Running post-action listener: _resource_record_post_action 11:11:00 INFO - [mozharness: 2017-10-02 18:11:00.993781Z] Finished run-tests step (success) 11:11:00 INFO - Running post-run listener: _resource_record_post_run 11:11:01 INFO - Total resource usage - Wall time: 1181s; CPU: 13.0%; Read bytes: 65536; Write bytes: 279822336; Read time: 196; Write time: 151612 11:11:01 INFO - TinderboxPrint: CPU usage
13.0% 11:11:01 INFO - TinderboxPrint: I/O read bytes / time
65,536 / 196 11:11:01 INFO - TinderboxPrint: I/O write bytes / time
279,822,336 / 151,612 11:11:01 INFO - TinderboxPrint: CPU idle
8,191.0 (86.9%) 11:11:01 INFO - TinderboxPrint: CPU user
1,179.2 (12.5%) 11:11:01 INFO - TinderboxPrint: Swap in / out
0 / 0 11:11:01 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 507904; Read time: 0; Write time: 48 11:11:01 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:11:01 INFO - run-tests - Wall time: 1166s; CPU: 13.0%; Read bytes: 65536; Write bytes: 279314432; Read time: 196; Write time: 151564 11:11:01 INFO - Running post-run listener: _upload_blobber_files 11:11:01 INFO - Blob upload gear active. 11:11:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:11:01 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:11:01 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'] 11:11:01 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 11:11:02 INFO - (blobuploader) - INFO - Open directory for files ... 11:11:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:11:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:11:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:11:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:11:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:11:03 INFO - (blobuploader) - INFO - Done attempting. 11:11:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:11:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:11:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:11:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:11:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:11:04 INFO - (blobuploader) - INFO - Done attempting. 11:11:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:11:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:11:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:11:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:11:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:11:18 INFO - (blobuploader) - INFO - Done attempting. 11:11:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:11:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:11:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:11:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:11:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:11:20 INFO - (blobuploader) - INFO - Done attempting. 11:11:20 INFO - (blobuploader) - INFO - Iteration through files over. 11:11:20 INFO - Return code: 0 11:11:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:11:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:11:20 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/15c3504763746bafa49cc6dbec39197afc37ab2a9100603c473bae3ad077d35092df0fa39c01c27aa4dccd0ff7444b6d9b16a40fffd6461e87c876b14547ad18", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/2e4c4defe3acbedc894eae55521943dc3adf7e83eefafa62ea856a3ccb7c34381ac7b354fe7b86b530a63de09a293bb2b5dcf47febbf226868f6eafbaefc4986", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4c21afcbbdef6f6fc657f14ab9446ccc877c3d8d5bfe74102830981c07d5d2ad801b07d9e0282aa51827d1fa60ef3bfc9237fcfed0b78c7f8756e3d1e7f2fb0b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/109d63c2c7ecb283339208b12360e355a6d7e7857a29660e8728b0cb6e243d4fed76e5369732fa6bf83e7a100c9951fba3aac442991a9f569ad6809f9956f4b2"} 11:11:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:11:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:11:20 INFO - Contents: 11:11:20 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/15c3504763746bafa49cc6dbec39197afc37ab2a9100603c473bae3ad077d35092df0fa39c01c27aa4dccd0ff7444b6d9b16a40fffd6461e87c876b14547ad18", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/2e4c4defe3acbedc894eae55521943dc3adf7e83eefafa62ea856a3ccb7c34381ac7b354fe7b86b530a63de09a293bb2b5dcf47febbf226868f6eafbaefc4986", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4c21afcbbdef6f6fc657f14ab9446ccc877c3d8d5bfe74102830981c07d5d2ad801b07d9e0282aa51827d1fa60ef3bfc9237fcfed0b78c7f8756e3d1e7f2fb0b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/109d63c2c7ecb283339208b12360e355a6d7e7857a29660e8728b0cb6e243d4fed76e5369732fa6bf83e7a100c9951fba3aac442991a9f569ad6809f9956f4b2"} 11:11:20 INFO - Running post-run listener: copy_logs_to_upload_dir 11:11:20 INFO - Copying logs to upload dir... 11:11:20 INFO - mkdir: /builds/slave/test/build/upload/logs 11:11:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1314.216946 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 54 secs) (at 2017-10-02 11:11:20.317114) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-02 11:11:20.321701) ========= 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=41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901 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/15c3504763746bafa49cc6dbec39197afc37ab2a9100603c473bae3ad077d35092df0fa39c01c27aa4dccd0ff7444b6d9b16a40fffd6461e87c876b14547ad18", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/2e4c4defe3acbedc894eae55521943dc3adf7e83eefafa62ea856a3ccb7c34381ac7b354fe7b86b530a63de09a293bb2b5dcf47febbf226868f6eafbaefc4986", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4c21afcbbdef6f6fc657f14ab9446ccc877c3d8d5bfe74102830981c07d5d2ad801b07d9e0282aa51827d1fa60ef3bfc9237fcfed0b78c7f8756e3d1e7f2fb0b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/109d63c2c7ecb283339208b12360e355a6d7e7857a29660e8728b0cb6e243d4fed76e5369732fa6bf83e7a100c9951fba3aac442991a9f569ad6809f9956f4b2"} build_url:https://queue.taskcluster.net/v1/task/Xl0qvk8-QiGQc5almAaDCQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011287 build_url: 'https://queue.taskcluster.net/v1/task/Xl0qvk8-QiGQc5almAaDCQ/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/15c3504763746bafa49cc6dbec39197afc37ab2a9100603c473bae3ad077d35092df0fa39c01c27aa4dccd0ff7444b6d9b16a40fffd6461e87c876b14547ad18", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/2e4c4defe3acbedc894eae55521943dc3adf7e83eefafa62ea856a3ccb7c34381ac7b354fe7b86b530a63de09a293bb2b5dcf47febbf226868f6eafbaefc4986", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4c21afcbbdef6f6fc657f14ab9446ccc877c3d8d5bfe74102830981c07d5d2ad801b07d9e0282aa51827d1fa60ef3bfc9237fcfed0b78c7f8756e3d1e7f2fb0b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/109d63c2c7ecb283339208b12360e355a6d7e7857a29660e8728b0cb6e243d4fed76e5369732fa6bf83e7a100c9951fba3aac442991a9f569ad6809f9956f4b2"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-02 11:11:20.358485) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 11:11:20.358941) ========= 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=41bf3e9aed84707956354da8000001c5-1506964669.914229-1859175901 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004500 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 11:11:20.440084) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-02 11:11:20.443396) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-02 11:11:20.443776) ========= ========= Total master_lag: 0.23 =========