builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-027 starttime: 1506559873.11 results: success (0) revision: 5ebe2e8980c6fd3ede2b6617bbbc4073dd9b0369 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 17:51:13.106481) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 17:51:13.106930) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 17:51:13.107208) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 17:51:13.125496) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 17:51:13.125857) ========= 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=1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-27 17:51:13-- 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% 229M=0s 2017-09-27 17:51:13 (229 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.418674 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 17:51:13.575801) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 17:51:13.576127) ========= 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=1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013422 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 17:51:13.625408) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-27 17:51:13.625753) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5ebe2e8980c6fd3ede2b6617bbbc4073dd9b0369 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 5ebe2e8980c6fd3ede2b6617bbbc4073dd9b0369 --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=1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-27 17:51:13,661 truncating revision to first 12 chars 2017-09-27 17:51:13,661 Setting DEBUG logging. 2017-09-27 17:51:13,661 attempt 1/10 2017-09-27 17:51:13,661 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5ebe2e8980c6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-27 17:51:15,009 unpacking tar archive at: mozilla-central-5ebe2e8980c6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.646676 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-27 17:51:15.290170) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 17:51:15.293565) ========= script_repo_revision: 5ebe2e8980c6fd3ede2b6617bbbc4073dd9b0369 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 17:51:15.293992) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 17:51:15.294251) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 17:51:15.312335) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2017-09-27 17:51:15.312753) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:51:15 INFO - MultiFileLogger online at 20170927 17:51:15 in /builds/slave/test 17:51:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 17:51:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:51:15 INFO - {'append_to_log': False, 17:51:15 INFO - 'base_work_dir': '/builds/slave/test', 17:51:15 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 17:51:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:51:15 INFO - 'branch': 'Firefox-Non-PGO', 17:51:15 INFO - 'buildbot_json_path': 'buildprops.json', 17:51:15 INFO - 'code_coverage': False, 17:51:15 INFO - 'config_files': ('talos/linux_config.py',), 17:51:15 INFO - 'default_actions': ('clobber', 17:51:15 INFO - 'read-buildbot-config', 17:51:15 INFO - 'download-and-extract', 17:51:15 INFO - 'populate-webroot', 17:51:15 INFO - 'create-virtualenv', 17:51:15 INFO - 'install', 17:51:15 INFO - 'setup-mitmproxy', 17:51:15 INFO - 'run-tests'), 17:51:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:51:15 INFO - 'disable_ccov_upload': False, 17:51:15 INFO - 'disable_stylo': False, 17:51:15 INFO - 'download_minidump_stackwalk': True, 17:51:15 INFO - 'download_symbols': 'ondemand', 17:51:15 INFO - 'enable_stylo': False, 17:51:15 INFO - 'enable_webrender': False, 17:51:15 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 17:51:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:51:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:51:15 INFO - 'gecko_profile': False, 17:51:15 INFO - 'gecko_profile_interval': 0, 17:51:15 INFO - 'installer_path': 'installer.exe', 17:51:15 INFO - 'log_level': 'info', 17:51:15 INFO - 'log_name': 'talos', 17:51:15 INFO - 'log_to_console': True, 17:51:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:51:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:51:15 INFO - 'opt_config_files': (), 17:51:15 INFO - 'pip_index': False, 17:51:15 INFO - 'suite': 'dromaeojs-e10s', 17:51:15 INFO - 'system_bits': '32', 17:51:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:51:15 INFO - 'title': 'talos-linux64-ix-027', 17:51:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:51:15 INFO - 'use_talos_json': True, 17:51:15 INFO - 'verify': 'False', 17:51:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:51:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:51:15 INFO - 'work_dir': 'build'} 17:51:15 INFO - [mozharness: 2017-09-28 00:51:15.580448Z] Running clobber step. 17:51:15 INFO - Running pre-action listener: _resource_record_pre_action 17:51:15 INFO - Running main action method: clobber 17:51:15 INFO - rmtree: /builds/slave/test/build 17:51:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:51:16 INFO - Running post-action listener: _resource_record_post_action 17:51:16 INFO - [mozharness: 2017-09-28 00:51:16.529921Z] Finished clobber step (success) 17:51:16 INFO - [mozharness: 2017-09-28 00:51:16.530080Z] Running read-buildbot-config step. 17:51:16 INFO - Running pre-action listener: _resource_record_pre_action 17:51:16 INFO - Running main action method: read_buildbot_config 17:51:16 INFO - Using buildbot properties: 17:51:16 INFO - { 17:51:16 INFO - "project": "", 17:51:16 INFO - "product": "firefox", 17:51:16 INFO - "who": "kwierso@gmail.com", 17:51:16 INFO - "installer_path": "public/build/target.tar.bz2", 17:51:16 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 17:51:16 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 17:51:16 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 17:51:16 INFO - "stage_platform": "linux64", 17:51:16 INFO - "basedir": "/builds/slave/test", 17:51:16 INFO - "buildnumber": 875, 17:51:16 INFO - "platform": "ubuntu64_hw", 17:51:16 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:51:16 INFO - "slavebuilddir": "test", 17:51:16 INFO - "taskId": "e29cYysGQVCW2y73YEJVAw", 17:51:16 INFO - "branch": "mozilla-central", 17:51:16 INFO - "script_repo_revision": "production", 17:51:16 INFO - "revision": "5ebe2e8980c6fd3ede2b6617bbbc4073dd9b0369", 17:51:16 INFO - "slavename": "talos-linux64-ix-027", 17:51:16 INFO - "repo_path": "mozilla-central" 17:51:16 INFO - } 17:51:16 INFO - Finding installer, test and symbols from parent task. 17:51:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e29cYysGQVCW2y73YEJVAw'}, attempt #1 17:51:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e29cYysGQVCW2y73YEJVAw'}, attempt #1 17:51:17 INFO - Task dependencies: ROcRQ0zqS7mg-39GJg0GHQ 17:51:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ'}, attempt #1 17:51:17 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.tar.bz2 17:51:17 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.test_packages.json 17:51:17 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.crashreporter-symbols.zip 17:51:17 INFO - Running post-action listener: _resource_record_post_action 17:51:17 INFO - [mozharness: 2017-09-28 00:51:17.888959Z] Finished read-buildbot-config step (success) 17:51:17 INFO - [mozharness: 2017-09-28 00:51:17.889157Z] Running download-and-extract step. 17:51:17 INFO - Running pre-action listener: _resource_record_pre_action 17:51:17 INFO - Running main action method: download_and_extract 17:51:17 INFO - mkdir: /builds/slave/test/build/tests 17: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')]} 17:51:17 INFO - trying https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.test_packages.json 17:51:17 INFO - Downloading https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:51:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:51:18 INFO - Downloaded 989 bytes. 17:51:18 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:51:18 INFO - Using the following test package requirements: 17:51:18 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:51:18 INFO - u'common': [u'target.common.tests.zip'], 17:51:18 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:51:18 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:51:18 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:51:18 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:51:18 INFO - u'mozbase': [u'target.common.tests.zip'], 17:51:18 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:51:18 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:51:18 INFO - u'web-platform': [u'target.common.tests.zip', 17:51:18 INFO - u'target.web-platform.tests.tar.gz'], 17:51:18 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:51:18 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 17:51:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.common.tests.zip 17:51:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:51:18 INFO - Fetch https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.common.tests.zip into memory 17:51:19 INFO - Content-Length response header: 54946002 17:51:19 INFO - Bytes received: 54946002 17:51:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.talos.tests.zip 17:51:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:51:30 INFO - Fetch https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.talos.tests.zip into memory 17:51:31 INFO - Content-Length response header: 14521162 17:51:31 INFO - Bytes received: 14521162 17:51:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:31 INFO - trying https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.tar.bz2 17:51:31 INFO - Downloading https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:51:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:51:32 INFO - Downloaded 61824142 bytes. 17:51:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.tar.bz2 17:51:32 INFO - mkdir: /builds/slave/test/properties 17:51:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:51:32 INFO - Writing to file /builds/slave/test/properties/build_url 17:51:32 INFO - Contents: 17:51:32 INFO - build_url:https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.tar.bz2 17:51:32 INFO - Running post-action listener: _resource_record_post_action 17:51:32 INFO - Running post-action listener: find_tests_for_verification 17:51:32 INFO - Running post-action listener: set_extra_try_arguments 17:51:32 INFO - [mozharness: 2017-09-28 00:51:32.853937Z] Finished download-and-extract step (success) 17:51:32 INFO - [mozharness: 2017-09-28 00:51:32.854081Z] Running populate-webroot step. 17:51:32 INFO - Running pre-action listener: _resource_record_pre_action 17:51:32 INFO - Running main action method: populate_webroot 17:51:32 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:51:32 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:51:32 INFO - 'tests': ['tresize', 17:51:32 INFO - 'tcanvasmark']}, 17:51:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:51:32 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:51:32 INFO - 'tests': ['dromaeo_css', 17:51:32 INFO - 'kraken']}, 17:51:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:51:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:51:32 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:51:32 INFO - 'talos_options': ['--disable-stylo'], 17:51:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:51:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:51:32 INFO - 'tests': ['damp', 'tps']}, 17:51:32 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:51:32 INFO - 'talos_options': ['--disable-stylo'], 17:51:32 INFO - 'tests': ['damp', 'tps']}, 17:51:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:51:32 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:51:32 INFO - 'tests': ['dromaeo_dom']}, 17:51:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:51:32 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:51:32 INFO - 'tests': ['basic_compositor_video', 17:51:32 INFO - 'glvideo']}, 17:51:32 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:51:32 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:51:32 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:51:32 INFO - 'talos_options': ['--disable-stylo'], 17:51:32 INFO - 'tests': ['ts_paint_webext', 17:51:32 INFO - 'tp5o_webext']}, 17:51:32 INFO - 'other-e10s': {'tests': ['a11yr', 17:51:32 INFO - 'ts_paint', 17:51:32 INFO - 'tpaint', 17:51:32 INFO - 'sessionrestore', 17:51:32 INFO - 'sessionrestore_many_windows', 17:51:32 INFO - 'sessionrestore_no_auto_restore', 17:51:32 INFO - 'tabpaint', 17:51:32 INFO - 'cpstartup']}, 17:51:32 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:51:32 INFO - 'tests': ['a11yr', 17:51:32 INFO - 'ts_paint', 17:51:32 INFO - 'tpaint', 17:51:32 INFO - 'sessionrestore', 17:51:32 INFO - 'sessionrestore_many_windows', 17:51:32 INFO - 'sessionrestore_no_auto_restore', 17:51:32 INFO - 'tabpaint', 17:51:32 INFO - 'cpstartup']}, 17:51:32 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 17:51:32 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 17:51:32 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:51:32 INFO - 'tests': ['perf_reftest_singletons']}, 17:51:32 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:51:32 INFO - 'tests': ['perf_reftest']}, 17:51:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:51:32 INFO - 'tsvgr_opacity', 17:51:32 INFO - 'tart', 17:51:32 INFO - 'tscrollx', 17:51:32 INFO - 'tsvg_static']}, 17:51:32 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:51:32 INFO - 'tests': ['tsvgx', 17:51:32 INFO - 'tsvgr_opacity', 17:51:32 INFO - 'tart', 17:51:32 INFO - 'tscrollx', 17:51:32 INFO - 'tsvg_static']}, 17:51:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:51:32 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:51:32 INFO - 'talos_options': ['--disable-stylo'], 17:51:32 INFO - 'tests': ['tp5o']}, 17:51:32 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:51:32 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:51:32 INFO - 'talos_options': ['--mitmproxy', 17:51:32 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:51:32 INFO - '--firstNonBlankPaint'], 17:51:32 INFO - 'tests': ['tp6_google', 17:51:32 INFO - 'tp6_youtube', 17:51:32 INFO - 'tp6_amazon', 17:51:32 INFO - 'tp6_facebook']}, 17:51:32 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:51:32 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:51:32 INFO - 'talos_options': ['--disable-stylo', 17:51:32 INFO - '--mitmproxy', 17:51:32 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:51:32 INFO - '--firstNonBlankPaint'], 17:51:32 INFO - 'tests': ['tp6_google', 17:51:32 INFO - 'tp6_youtube', 17:51:32 INFO - 'tp6_amazon', 17:51:32 INFO - 'tp6_facebook']}, 17:51:32 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:51:32 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:51:32 INFO - 'talos_options': ['--stylo-threads=1', 17:51:32 INFO - '--mitmproxy', 17:51:32 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:51:32 INFO - '--firstNonBlankPaint'], 17:51:32 INFO - 'tests': ['tp6_google', 17:51:32 INFO - 'tp6_youtube', 17:51:32 INFO - 'tp6_amazon', 17:51:32 INFO - 'tp6_facebook']}, 17:51:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:51:32 INFO - 'talos_options': ['--xperf_path', 17:51:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:51:32 INFO - 'tests': ['tp5n']}, 17:51:32 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:51:32 INFO - 'talos_options': ['--disable-stylo', 17:51:32 INFO - '--xperf_path', 17:51:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:51:32 INFO - 'tests': ['tp5n']}}} 17:51:32 INFO - Running post-action listener: _resource_record_post_action 17:51:32 INFO - [mozharness: 2017-09-28 00:51:32.872200Z] Finished populate-webroot step (success) 17:51:32 INFO - [mozharness: 2017-09-28 00:51:32.872288Z] Running create-virtualenv step. 17:51:32 INFO - Running pre-action listener: _resource_record_pre_action 17:51:32 INFO - Running main action method: create_virtualenv 17:51:32 INFO - Creating virtualenv /builds/slave/test/build/venv 17:51:32 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:51:32 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 17:51:32 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:51:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:51:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:51:33 INFO - Installing distribute.............................................................................................................................................................................................done. 17:51:33 INFO - Installing pip...............done. 17:51:33 INFO - Return code: 0 17:51:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:51:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:51:34 INFO - Reading from file tmpfile_stdout 17:51:34 INFO - Output received: 17:51:34 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 17:51:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17: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')]} 17:51:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17: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')]} 17:51:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:34 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 0x1dd01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f98b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x213d9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213ad60>, '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 0x213ba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x213bef0>, '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': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', '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 17:51:34 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 17:51:34 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 17:51:34 INFO - Using env: {'DISPLAY': ':0', 17:51:34 INFO - 'HOME': '/home/cltbld', 17:51:34 INFO - 'LANG': 'en_US.UTF-8', 17:51:34 INFO - 'LANGUAGE': 'en_US:en', 17:51:34 INFO - 'LOGNAME': 'cltbld', 17:51:34 INFO - 'MAIL': '/var/mail/cltbld', 17:51:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:34 INFO - 'MOZ_NO_REMOTE': '1', 17:51:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:51:34 INFO - 'NO_EM_RESTART': '1', 17:51:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:51:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:34 INFO - 'PWD': '/builds/slave/test', 17:51:34 INFO - 'SHELL': '/bin/bash', 17:51:34 INFO - 'SHLVL': '1', 17:51:34 INFO - 'TERM': 'linux', 17:51:34 INFO - 'TMOUT': '86400', 17:51:34 INFO - 'USER': 'cltbld', 17:51:34 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', 17:51:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:34 INFO - '_': '/tools/buildbot/bin/python'} 17:51:36 INFO - Ignoring indexes: http://pypi.python.org/simple/ 17:51:36 INFO - Downloading/unpacking pip>=1.5 17:51:36 INFO - Running setup.py egg_info for package pip 17:51:36 INFO - warning: no files found matching 'pip/cacert.pem' 17:51:36 INFO - warning: no files found matching '*.html' under directory 'docs' 17:51:36 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:51:36 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:51:36 INFO - Installing collected packages: pip 17:51:36 INFO - Found existing installation: pip 1.1 17:51:36 INFO - Uninstalling pip: 17:51:36 INFO - Successfully uninstalled pip 17:51:36 INFO - Running setup.py install for pip 17:51:36 INFO - warning: no files found matching 'pip/cacert.pem' 17:51:36 INFO - warning: no files found matching '*.html' under directory 'docs' 17:51:36 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:51:36 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:51:36 INFO - Installing pip script to /builds/slave/test/build/venv/bin 17:51:36 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 17:51:36 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 17:51:36 INFO - Successfully installed pip 17:51:36 INFO - Cleaning up... 17:51:36 INFO - Return code: 0 17:51:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:51:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1dd01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f98b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x213d9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213ad60>, '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 0x213ba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x213bef0>, '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': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', '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 17:51:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:51:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:51:36 INFO - Using env: {'DISPLAY': ':0', 17:51:36 INFO - 'HOME': '/home/cltbld', 17:51:36 INFO - 'LANG': 'en_US.UTF-8', 17:51:36 INFO - 'LANGUAGE': 'en_US:en', 17:51:36 INFO - 'LOGNAME': 'cltbld', 17:51:36 INFO - 'MAIL': '/var/mail/cltbld', 17:51:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:36 INFO - 'MOZ_NO_REMOTE': '1', 17:51:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:51:36 INFO - 'NO_EM_RESTART': '1', 17:51:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:51:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:36 INFO - 'PWD': '/builds/slave/test', 17:51:36 INFO - 'SHELL': '/bin/bash', 17:51:36 INFO - 'SHLVL': '1', 17:51:36 INFO - 'TERM': 'linux', 17:51:36 INFO - 'TMOUT': '86400', 17:51:36 INFO - 'USER': 'cltbld', 17:51:36 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', 17:51:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:36 INFO - '_': '/tools/buildbot/bin/python'} 17:51:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:37 INFO - Downloading/unpacking psutil>=3.1.1 17:51:37 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 17:51:37 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 17:51:37 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 17:51:37 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 17:51:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:51:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:51:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:51:38 INFO - Installing collected packages: psutil 17:51:38 INFO - Running setup.py install for psutil 17:51:38 INFO - building 'psutil._psutil_linux' extension 17:51:38 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 17:51:39 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 17:51:39 INFO - building 'psutil._psutil_posix' extension 17:51:39 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 17:51:39 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 17:51:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:51:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:51:39 INFO - Successfully installed psutil 17:51:39 INFO - Cleaning up... 17:51:39 INFO - Return code: 0 17:51:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:51:39 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')]} 17:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:39 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')]} 17:51:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:39 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 0x1dd01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f98b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x213d9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213ad60>, '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 0x213ba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x213bef0>, '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': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', '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 17:51:39 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 17:51:39 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 17:51:39 INFO - Using env: {'DISPLAY': ':0', 17:51:39 INFO - 'HOME': '/home/cltbld', 17:51:39 INFO - 'LANG': 'en_US.UTF-8', 17:51:39 INFO - 'LANGUAGE': 'en_US:en', 17:51:39 INFO - 'LOGNAME': 'cltbld', 17:51:39 INFO - 'MAIL': '/var/mail/cltbld', 17:51:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:39 INFO - 'MOZ_NO_REMOTE': '1', 17:51:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:51:39 INFO - 'NO_EM_RESTART': '1', 17:51:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:51:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:39 INFO - 'PWD': '/builds/slave/test', 17:51:39 INFO - 'SHELL': '/bin/bash', 17:51:39 INFO - 'SHLVL': '1', 17:51:39 INFO - 'TERM': 'linux', 17:51:39 INFO - 'TMOUT': '86400', 17:51:39 INFO - 'USER': 'cltbld', 17:51:39 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', 17:51:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:39 INFO - '_': '/tools/buildbot/bin/python'} 17:51:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:51:39 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 17:51:39 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 17:51:39 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 17:51:39 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 17:51:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:51:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:51:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:51:41 INFO - Installing collected packages: mozsystemmonitor 17:51:41 INFO - Running setup.py install for mozsystemmonitor 17:51:41 INFO - Successfully installed mozsystemmonitor 17:51:41 INFO - Cleaning up... 17:51:41 INFO - Return code: 0 17:51:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:51:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1dd01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f98b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x213d9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213ad60>, '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 0x213ba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x213bef0>, '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': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', '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 17:51:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:51:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:51:41 INFO - Using env: {'DISPLAY': ':0', 17:51:41 INFO - 'HOME': '/home/cltbld', 17:51:41 INFO - 'LANG': 'en_US.UTF-8', 17:51:41 INFO - 'LANGUAGE': 'en_US:en', 17:51:41 INFO - 'LOGNAME': 'cltbld', 17:51:41 INFO - 'MAIL': '/var/mail/cltbld', 17:51:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:41 INFO - 'MOZ_NO_REMOTE': '1', 17:51:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:51:41 INFO - 'NO_EM_RESTART': '1', 17:51:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:51:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:41 INFO - 'PWD': '/builds/slave/test', 17:51:41 INFO - 'SHELL': '/bin/bash', 17:51:41 INFO - 'SHLVL': '1', 17:51:41 INFO - 'TERM': 'linux', 17:51:41 INFO - 'TMOUT': '86400', 17:51:41 INFO - 'USER': 'cltbld', 17:51:41 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', 17:51:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:41 INFO - '_': '/tools/buildbot/bin/python'} 17:51:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:41 INFO - Downloading/unpacking jsonschema==2.5.1 17:51:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:51:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:51:42 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 17:51:42 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 17:51:42 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 17:51:42 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 17:51:42 INFO - Downloading functools32-3.2.3-2.tar.gz 17:51:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:51:42 INFO - warning: no files found matching '*.txt' 17:51:42 INFO - no previously-included directories found matching 'build' 17:51:42 INFO - no previously-included directories found matching 'dist' 17:51:42 INFO - no previously-included directories found matching '.git*' 17:51:42 INFO - Installing collected packages: jsonschema, functools32 17:51:42 INFO - Running setup.py install for functools32 17:51:42 INFO - warning: no files found matching '*.txt' 17:51:42 INFO - no previously-included directories found matching 'build' 17:51:42 INFO - no previously-included directories found matching 'dist' 17:51:42 INFO - no previously-included directories found matching '.git*' 17:51:42 INFO - Successfully installed jsonschema functools32 17:51:42 INFO - Cleaning up... 17:51:43 INFO - Return code: 0 17:51:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:51:43 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')]} 17:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:43 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')]} 17:51:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:43 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 0x1dd01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f98b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x213d9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213ad60>, '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 0x213ba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x213bef0>, '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': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', '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 17:51:43 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 17:51:43 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 17:51:43 INFO - Using env: {'DISPLAY': ':0', 17:51:43 INFO - 'HOME': '/home/cltbld', 17:51:43 INFO - 'LANG': 'en_US.UTF-8', 17:51:43 INFO - 'LANGUAGE': 'en_US:en', 17:51:43 INFO - 'LOGNAME': 'cltbld', 17:51:43 INFO - 'MAIL': '/var/mail/cltbld', 17:51:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:43 INFO - 'MOZ_NO_REMOTE': '1', 17:51:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:51:43 INFO - 'NO_EM_RESTART': '1', 17:51:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:51:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:43 INFO - 'PWD': '/builds/slave/test', 17:51:43 INFO - 'SHELL': '/bin/bash', 17:51:43 INFO - 'SHLVL': '1', 17:51:43 INFO - 'TERM': 'linux', 17:51:43 INFO - 'TMOUT': '86400', 17:51:43 INFO - 'USER': 'cltbld', 17:51:43 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', 17:51:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:43 INFO - '_': '/tools/buildbot/bin/python'} 17:51:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:51:43 INFO - Cleaning up... 17:51:43 INFO - Return code: 0 17:51:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:51:43 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')]} 17:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:43 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')]} 17:51:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:43 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 0x1dd01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f98b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x213d9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213ad60>, '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 0x213ba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x213bef0>, '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': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', '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 17:51:43 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 17:51:43 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 17:51:43 INFO - Using env: {'DISPLAY': ':0', 17:51:43 INFO - 'HOME': '/home/cltbld', 17:51:43 INFO - 'LANG': 'en_US.UTF-8', 17:51:43 INFO - 'LANGUAGE': 'en_US:en', 17:51:43 INFO - 'LOGNAME': 'cltbld', 17:51:43 INFO - 'MAIL': '/var/mail/cltbld', 17:51:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:43 INFO - 'MOZ_NO_REMOTE': '1', 17:51:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:51:43 INFO - 'NO_EM_RESTART': '1', 17:51:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:51:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:43 INFO - 'PWD': '/builds/slave/test', 17:51:43 INFO - 'SHELL': '/bin/bash', 17:51:43 INFO - 'SHLVL': '1', 17:51:43 INFO - 'TERM': 'linux', 17:51:43 INFO - 'TMOUT': '86400', 17:51:43 INFO - 'USER': 'cltbld', 17:51:43 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', 17:51:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:43 INFO - '_': '/tools/buildbot/bin/python'} 17:51:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:43 INFO - Downloading/unpacking blobuploader==1.2.4 17:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:44 INFO - Downloading blobuploader-1.2.4.tar.gz 17:51:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:51:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:51:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:51:45 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 17:51:45 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 17:51:45 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 17:51:45 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 17:51:45 INFO - Downloading docopt-0.6.1.tar.gz 17:51:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:51:45 INFO - Installing collected packages: blobuploader, requests, docopt 17:51:45 INFO - Running setup.py install for blobuploader 17:51:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:51:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:51:45 INFO - Running setup.py install for requests 17:51:46 INFO - Running setup.py install for docopt 17:51:46 INFO - Successfully installed blobuploader requests docopt 17:51:46 INFO - Cleaning up... 17:51:46 INFO - Return code: 0 17:51:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:51:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:46 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 0x1dd01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f98b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x213d9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213ad60>, '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 0x213ba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x213bef0>, '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': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', '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 17:51:46 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 17:51:46 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 17:51:46 INFO - Using env: {'DISPLAY': ':0', 17:51:46 INFO - 'HOME': '/home/cltbld', 17:51:46 INFO - 'LANG': 'en_US.UTF-8', 17:51:46 INFO - 'LANGUAGE': 'en_US:en', 17:51:46 INFO - 'LOGNAME': 'cltbld', 17:51:46 INFO - 'MAIL': '/var/mail/cltbld', 17:51:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:46 INFO - 'MOZ_NO_REMOTE': '1', 17:51:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:51:46 INFO - 'NO_EM_RESTART': '1', 17:51:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:51:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:46 INFO - 'PWD': '/builds/slave/test', 17:51:46 INFO - 'SHELL': '/bin/bash', 17:51:46 INFO - 'SHLVL': '1', 17:51:46 INFO - 'TERM': 'linux', 17:51:46 INFO - 'TMOUT': '86400', 17:51:46 INFO - 'USER': 'cltbld', 17:51:46 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', 17:51:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:46 INFO - '_': '/tools/buildbot/bin/python'} 17:51:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:51:46 INFO - Running setup.py (path:/tmp/pip-mnoG3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:51:46 INFO - Running setup.py (path:/tmp/pip-fTueHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:51:46 INFO - Running setup.py (path:/tmp/pip-KgRx7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:51:46 INFO - Running setup.py (path:/tmp/pip-Cr000A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:51:46 INFO - Running setup.py (path:/tmp/pip-HEHIzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:51:46 INFO - Running setup.py (path:/tmp/pip-SoRFav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:51:46 INFO - Running setup.py (path:/tmp/pip-ovoT3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:51:47 INFO - Running setup.py (path:/tmp/pip-kVqwtX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:51:47 INFO - Running setup.py (path:/tmp/pip-pxkWef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:51:47 INFO - Running setup.py (path:/tmp/pip-STV4AI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:51:47 INFO - Running setup.py (path:/tmp/pip-ixi8Ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:51:47 INFO - Running setup.py (path:/tmp/pip-Lc23_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:51:47 INFO - Running setup.py (path:/tmp/pip-Zjsbbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:51:47 INFO - Running setup.py (path:/tmp/pip-ghAwWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:51:47 INFO - Running setup.py (path:/tmp/pip-JgvbFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:51:47 INFO - Running setup.py (path:/tmp/pip-7A9Saf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:51:47 INFO - Running setup.py (path:/tmp/pip-I5zzmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:51:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:51:47 INFO - Running setup.py install for manifestparser 17:51:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:51:47 INFO - Running setup.py install for mozcrash 17:51:47 INFO - Running setup.py install for mozdebug 17:51:47 INFO - Running setup.py install for mozdevice 17:51:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:51:48 INFO - Running setup.py install for mozfile 17:51:48 INFO - Running setup.py install for mozhttpd 17:51:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:51:48 INFO - Running setup.py install for mozinfo 17:51:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:51:48 INFO - Running setup.py install for mozInstall 17:51:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:51:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:51:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:51:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:51:48 INFO - Running setup.py install for mozleak 17:51:48 INFO - Running setup.py install for mozlog 17:51:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:51:48 INFO - Running setup.py install for moznetwork 17:51:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:51:48 INFO - Running setup.py install for mozprocess 17:51:48 INFO - Running setup.py install for mozprofile 17:51:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:51:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:51:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:51:48 INFO - Running setup.py install for mozrunner 17:51:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:51:49 INFO - Running setup.py install for mozscreenshot 17:51:49 INFO - Running setup.py install for moztest 17:51:49 INFO - Running setup.py install for mozversion 17:51:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:51:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:51:49 INFO - Cleaning up... 17:51:49 INFO - Return code: 0 17:51:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:51: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')]} 17:51:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51: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')]} 17:51:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:49 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 0x1dd01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f98b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x213d9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213ad60>, '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 0x213ba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x213bef0>, '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': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', '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 17:51:49 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 17:51:49 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 17:51:49 INFO - Using env: {'DISPLAY': ':0', 17:51:49 INFO - 'HOME': '/home/cltbld', 17:51:49 INFO - 'LANG': 'en_US.UTF-8', 17:51:49 INFO - 'LANGUAGE': 'en_US:en', 17:51:49 INFO - 'LOGNAME': 'cltbld', 17:51:49 INFO - 'MAIL': '/var/mail/cltbld', 17:51:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:49 INFO - 'MOZ_NO_REMOTE': '1', 17:51:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:51:49 INFO - 'NO_EM_RESTART': '1', 17:51:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:51:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:49 INFO - 'PWD': '/builds/slave/test', 17:51:49 INFO - 'SHELL': '/bin/bash', 17:51:49 INFO - 'SHLVL': '1', 17:51:49 INFO - 'TERM': 'linux', 17:51:49 INFO - 'TMOUT': '86400', 17:51:49 INFO - 'USER': 'cltbld', 17:51:49 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', 17:51:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:49 INFO - '_': '/tools/buildbot/bin/python'} 17:51:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:51:49 INFO - Running setup.py (path:/tmp/pip-ZFboIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:51:49 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)) 17:51:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:51:49 INFO - Running setup.py (path:/tmp/pip-sNUkEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:51:49 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)) 17:51:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:51:49 INFO - Running setup.py (path:/tmp/pip-uqCt6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:51:49 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)) 17:51:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:51:49 INFO - Running setup.py (path:/tmp/pip-G6y3VM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:51:49 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)) 17:51:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:51:49 INFO - Running setup.py (path:/tmp/pip-aLrByl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:51:49 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)) 17:51:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:51:49 INFO - Running setup.py (path:/tmp/pip-IvjJX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:51:49 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)) 17:51:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:51:49 INFO - Running setup.py (path:/tmp/pip-SrgmFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:51:50 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)) 17:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:51:50 INFO - Running setup.py (path:/tmp/pip-js2YDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:51:50 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)) 17:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:51:50 INFO - Running setup.py (path:/tmp/pip-CWLGS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:51:50 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)) 17:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:51:50 INFO - Running setup.py (path:/tmp/pip-Wj7iGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:51:50 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)) 17:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:51:50 INFO - Running setup.py (path:/tmp/pip-qnYmR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:51:50 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)) 17:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:51:50 INFO - Running setup.py (path:/tmp/pip-kY7V1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:51:50 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)) 17:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:51:50 INFO - Running setup.py (path:/tmp/pip-cT209Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:51:50 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)) 17:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:51:50 INFO - Running setup.py (path:/tmp/pip-FP4H3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:51:50 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)) 17:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:51:50 INFO - Running setup.py (path:/tmp/pip-3_9S4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:51:50 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)) 17:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:51:50 INFO - Running setup.py (path:/tmp/pip-VElgn8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:51:50 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)) 17:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:51:50 INFO - Running setup.py (path:/tmp/pip-zYF4jC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:51:50 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)) 17:51:50 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)) 17:51:50 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)) 17:51:50 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)) 17:51:50 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)) 17:51:50 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)) 17:51:50 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)) 17:51:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:51:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:51 INFO - Downloading blessings-1.6.tar.gz 17:51:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:51:51 INFO - Installing collected packages: blessings 17:51:51 INFO - Running setup.py install for blessings 17:51:52 INFO - Successfully installed blessings 17:51:52 INFO - Cleaning up... 17:51:52 INFO - Return code: 0 17:51:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:51:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:51:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:51:52 INFO - Reading from file tmpfile_stdout 17:51:52 INFO - Current package versions: 17:51:52 INFO - argparse == 1.2.1 17:51:52 INFO - blessings == 1.6 17:51:52 INFO - blobuploader == 1.2.4 17:51:52 INFO - docopt == 0.6.1 17:51:52 INFO - functools32 == 3.2.3-2 17:51:52 INFO - jsonschema == 2.5.1 17:51:52 INFO - manifestparser == 1.1 17:51:52 INFO - mozInstall == 1.13 17:51:52 INFO - mozcrash == 1.0 17:51:52 INFO - mozdebug == 0.1 17:51:52 INFO - mozdevice == 0.51 17:51:52 INFO - mozfile == 1.2 17:51:52 INFO - mozhttpd == 0.7 17:51:52 INFO - mozinfo == 0.10 17:51:52 INFO - mozleak == 0.1 17:51:52 INFO - mozlog == 3.5 17:51:52 INFO - moznetwork == 0.27 17:51:52 INFO - mozprocess == 0.25 17:51:52 INFO - mozprofile == 0.28 17:51:52 INFO - mozrunner == 6.13 17:51:52 INFO - mozscreenshot == 0.1 17:51:52 INFO - mozsystemmonitor == 0.3 17:51:52 INFO - moztest == 0.8 17:51:52 INFO - mozversion == 1.4 17:51:52 INFO - psutil == 3.1.1 17:51:52 INFO - requests == 1.2.3 17:51:52 INFO - wsgiref == 0.1.2 17:51:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:51:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:52 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 0x1dd01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f98b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x213d9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213ad60>, '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 0x213ba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x213bef0>, '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': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', '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 17:51:52 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 17:51:52 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 17:51:52 INFO - Using env: {'DISPLAY': ':0', 17:51:52 INFO - 'HOME': '/home/cltbld', 17:51:52 INFO - 'LANG': 'en_US.UTF-8', 17:51:52 INFO - 'LANGUAGE': 'en_US:en', 17:51:52 INFO - 'LOGNAME': 'cltbld', 17:51:52 INFO - 'MAIL': '/var/mail/cltbld', 17:51:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:52 INFO - 'MOZ_NO_REMOTE': '1', 17:51:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:51:52 INFO - 'NO_EM_RESTART': '1', 17:51:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:51:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:52 INFO - 'PWD': '/builds/slave/test', 17:51:52 INFO - 'SHELL': '/bin/bash', 17:51:52 INFO - 'SHLVL': '1', 17:51:52 INFO - 'TERM': 'linux', 17:51:52 INFO - 'TMOUT': '86400', 17:51:52 INFO - 'USER': 'cltbld', 17:51:52 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', 17:51:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:52 INFO - '_': '/tools/buildbot/bin/python'} 17:51:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:52 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)) 17:51:52 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)) 17:51:52 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)) 17:51:52 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)) 17:51:52 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)) 17:51:52 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)) 17:51:52 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)) 17:51:52 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)) 17:51:52 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)) 17:51:52 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 17:51:53 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)) 17:51:53 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)) 17:51:53 INFO - Installing collected packages: simplejson 17:51:53 INFO - Running setup.py install for simplejson 17:51:53 INFO - building 'simplejson._speedups' extension 17:51:53 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 17:51:54 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 17:51:54 INFO - Successfully installed simplejson 17:51:54 INFO - Cleaning up... 17:51:54 INFO - Return code: 0 17:51:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:51:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f98b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x213d9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213ad60>, '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 0x213ba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x213bef0>, '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': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', '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 17:51:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:51:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:51:54 INFO - Using env: {'DISPLAY': ':0', 17:51:54 INFO - 'HOME': '/home/cltbld', 17:51:54 INFO - 'LANG': 'en_US.UTF-8', 17:51:54 INFO - 'LANGUAGE': 'en_US:en', 17:51:54 INFO - 'LOGNAME': 'cltbld', 17:51:54 INFO - 'MAIL': '/var/mail/cltbld', 17:51:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:54 INFO - 'MOZ_NO_REMOTE': '1', 17:51:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:51:54 INFO - 'NO_EM_RESTART': '1', 17:51:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:51:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:54 INFO - 'PWD': '/builds/slave/test', 17:51:54 INFO - 'SHELL': '/bin/bash', 17:51:54 INFO - 'SHLVL': '1', 17:51:54 INFO - 'TERM': 'linux', 17:51:54 INFO - 'TMOUT': '86400', 17:51:54 INFO - 'USER': 'cltbld', 17:51:54 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', 17:51:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:54 INFO - '_': '/tools/buildbot/bin/python'} 17:51:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:51:55 INFO - Cleaning up... 17:51:55 INFO - Return code: 0 17:51:55 INFO - Running post-action listener: _resource_record_post_action 17:51:55 INFO - Running post-action listener: _start_resource_monitoring 17:51:55 INFO - Starting resource monitoring. 17:51:55 INFO - [mozharness: 2017-09-28 00:51:55.104455Z] Finished create-virtualenv step (success) 17:51:55 INFO - [mozharness: 2017-09-28 00:51:55.105542Z] Running install step. 17:51:55 INFO - Running pre-action listener: _resource_record_pre_action 17:51:55 INFO - Running main action method: install 17:51:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:51:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:51:55 INFO - Reading from file tmpfile_stdout 17:51:55 INFO - Detecting whether we're running mozinstall >=1.0... 17:51:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:51:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:51:55 INFO - Reading from file tmpfile_stdout 17:51:55 INFO - Output received: 17:51:55 INFO - Usage: mozinstall [options] installer 17:51:55 INFO - Options: 17:51:55 INFO - -h, --help show this help message and exit 17:51:55 INFO - -d DEST, --destination=DEST 17:51:55 INFO - Directory to install application into. [default: 17:51:55 INFO - "/builds/slave/test"] 17:51:55 INFO - --app=APP Application being installed. [default: firefox] 17:51:55 INFO - mkdir: /builds/slave/test/build/application 17:51:55 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'] 17:51:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:52:13 INFO - Reading from file tmpfile_stdout 17:52:13 INFO - Output received: 17:52:13 INFO - /builds/slave/test/build/application/firefox/firefox 17:52:13 INFO - Running post-action listener: _resource_record_post_action 17:52:13 INFO - [mozharness: 2017-09-28 00:52:13.314383Z] Finished install step (success) 17:52:13 INFO - [mozharness: 2017-09-28 00:52:13.314637Z] Running setup-mitmproxy step. 17:52:13 INFO - Running pre-action listener: _resource_record_pre_action 17:52:13 INFO - Running main action method: setup_mitmproxy 17:52:13 INFO - Skipping: mitmproxy is not required 17:52:13 INFO - Running post-action listener: _resource_record_post_action 17:52:13 INFO - [mozharness: 2017-09-28 00:52:13.315769Z] Finished setup-mitmproxy step (success) 17:52:13 INFO - [mozharness: 2017-09-28 00:52:13.316004Z] Running run-tests step. 17:52:13 INFO - Running pre-action listener: _resource_record_pre_action 17:52:13 INFO - Running pre-action listener: _set_gcov_prefix 17:52:13 INFO - Running main action method: run_tests 17:52:13 WARNING - Try message not found. 17:52:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:52:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:52:13 INFO - Python 2.7.3 17:52:13 INFO - Return code: 0 17:52:13 INFO - grabbing minidump binary from tooltool 17:52:13 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')]} 17:52:13 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 0x213ad60>, '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 0x213ba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x213bef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:52:13 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 17:52:13 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 17:52:13 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 17:52:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:52:13 INFO - Return code: 0 17:52:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:52:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:52:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:52:13 INFO - ENV: RUST_BACKTRACE is now full 17:52:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:52:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:52:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 17:52:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-027 --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 17:52:13 INFO - Using env: {'DISPLAY': ':0', 17:52:13 INFO - 'HOME': '/home/cltbld', 17:52:13 INFO - 'LANG': 'en_US.UTF-8', 17:52:13 INFO - 'LANGUAGE': 'en_US:en', 17:52:13 INFO - 'LOGNAME': 'cltbld', 17:52:13 INFO - 'MAIL': '/var/mail/cltbld', 17:52:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:52:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:52:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:13 INFO - 'MOZ_NO_REMOTE': '1', 17:52:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:52:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:13 INFO - 'NO_EM_RESTART': '1', 17:52:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:52:13 INFO - 'PWD': '/builds/slave/test', 17:52:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:52:13 INFO - 'RUST_BACKTRACE': 'full', 17:52:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:52:13 INFO - 'SHELL': '/bin/bash', 17:52:13 INFO - 'SHLVL': '1', 17:52:13 INFO - 'TERM': 'linux', 17:52:13 INFO - 'TMOUT': '86400', 17:52:13 INFO - 'USER': 'cltbld', 17:52:13 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', 17:52:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:13 INFO - '_': '/tools/buildbot/bin/python'} 17:52:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 17:52:13 INFO - mozversion application_buildid: 20170927235256 17:52:13 INFO - mozversion application_changeset: 5ebe2e8980c6fd3ede2b6617bbbc4073dd9b0369 17:52:13 INFO - mozversion application_display_name: Nightly 17:52:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:52:13 INFO - mozversion application_name: Firefox 17:52:13 INFO - mozversion application_remotingname: firefox 17:52:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:52:13 INFO - mozversion application_vendor: Mozilla 17:52:13 INFO - mozversion application_version: 58.0a1 17:52:13 INFO - mozversion platform_buildid: 20170927235256 17:52:13 INFO - mozversion platform_changeset: 5ebe2e8980c6fd3ede2b6617bbbc4073dd9b0369 17:52:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:52:13 INFO - mozversion platform_version: 58.0a1 17:52:13 INFO - using testdate: 1506559933 17:52:13 INFO - actual date: 1506559933 17:52:13 INFO - starting webserver on 'localhost:57255' 17:52:13 INFO - SUITE-START | Running 2 tests 17:52:13 INFO - TEST-START | dromaeo_css 17:52:13 INFO - Initialising browser for dromaeo_css test... 17:52:13 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57255/getInfo.html -profile /tmp/tmpp0IVbs/profile 17:52:13 INFO - TEST-INFO | started process 3610 (/builds/slave/test/build/application/firefox/firefox http://localhost:57255/getInfo.html) 17:52:20 INFO - TEST-INFO | 3610: exit 0 17:52:20 INFO - Browser initialized. 17:52:20 INFO - Running cycle 1/1 for dromaeo_css test... 17:52:20 INFO - Using env: {'DISPLAY': ':0', 17:52:20 INFO - 'HOME': '/home/cltbld', 17:52:20 INFO - 'JSGC_DISABLE_POISONING': '1', 17:52:20 INFO - 'LANG': 'en_US.UTF-8', 17:52:20 INFO - 'LANGUAGE': 'en_US:en', 17:52:20 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:52:20 INFO - 'LOGNAME': 'cltbld', 17:52:20 INFO - 'MAIL': '/var/mail/cltbld', 17:52:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:52:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:52:20 INFO - 'MOZ_CRASHREPORTER': '1', 17:52:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:20 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:52:20 INFO - 'MOZ_NO_REMOTE': '1', 17:52:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:52:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:20 INFO - 'NO_EM_RESTART': '1', 17:52:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:52:20 INFO - 'PWD': '/builds/slave/test', 17:52:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:52:20 INFO - 'RUST_BACKTRACE': 'full', 17:52:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:52:20 INFO - 'SHELL': '/bin/bash', 17:52:20 INFO - 'SHLVL': '1', 17:52:20 INFO - 'TERM': 'linux', 17:52:20 INFO - 'TMOUT': '86400', 17:52:20 INFO - 'USER': 'cltbld', 17:52:20 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', 17:52:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:20 INFO - '_': '/tools/buildbot/bin/python'} 17:52:20 INFO - TEST-INFO | started process 3845 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp0IVbs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:52:21 INFO - PID 3845 | 17:52:21 INFO - PID 3845 | (/builds/slave/test/build/application/firefox/firefox:3894): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:52:21 INFO - PID 3845 | 17:52:31 INFO - PID 3845 | RSS: Main: 151662592 17:52:31 INFO - PID 3845 | 17:55:29 INFO - PID 3845 | Cycle 1(1): loaded http://localhost:57255/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57255/tests/dromaeo/cssquery-ext.html) 17:55:30 INFO - PID 3845 | RSS: Main: 149544960 17:55:30 INFO - PID 3845 | 17:58:32 INFO - PID 3845 | Cycle 1(1): loaded http://localhost:57255/tests/dromaeo/cssquery-ext.html (next: http://localhost:57255/tests/dromaeo/cssquery-jquery.html) 17:58:32 INFO - PID 3845 | RSS: Main: 149196800 17:58:32 INFO - PID 3845 | 18:01:34 INFO - PID 3845 | Cycle 1(1): loaded http://localhost:57255/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57255/tests/dromaeo/cssquery-mootools.html) 18:01:35 INFO - PID 3845 | RSS: Main: 151691264 18:01:35 INFO - PID 3845 | 18:04:36 INFO - PID 3845 | Cycle 1(1): loaded http://localhost:57255/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57255/tests/dromaeo/cssquery-prototype.html) 18:04:37 INFO - PID 3845 | RSS: Main: 151420928 18:04:37 INFO - PID 3845 | 18:07:40 INFO - PID 3845 | Cycle 1(1): loaded http://localhost:57255/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57255/tests/dromaeo/cssquery-yui.html) 18:07:41 INFO - PID 3845 | RSS: Main: 154288128 18:07:41 INFO - PID 3845 | 18:10:42 INFO - PID 3845 | Cycle 1(1): loaded http://localhost:57255/tests/dromaeo/cssquery-yui.html (next: http://localhost:57255/tests/dromaeo/cssquery-dojo.html) 18:10:43 INFO - PID 3845 | RSS: Main: 155820032 18:10:43 INFO - PID 3845 | 18:10:43 INFO - PID 3845 | __start_tp_report 18:10:43 INFO - PID 3845 | _x_x_mozilla_page_load 18:10:43 INFO - PID 3845 | _x_x_mozilla_page_load_details 18:10:43 INFO - PID 3845 | |i|pagename|runs| 18:10:43 INFO - PID 3845 | |0;dojo.html;3170.746340292777;3175.2538153533915;3199.2641692410743;3217.710406063454;3234.0782876880094;6117.66352850593;6303;6335.556511044223;6347.587406818554;6350.364963503647;6238;6246;6284.1516395286735;6363.490920726343;6363.9045414318825;5760.855978600547;5793.768249270024;5829.562782791287;5836.620619659709;5847.386024467433;5667.5749318801045;5742.569307301948;5801.912971305413;5849.473547380735;5860.326062502802;5723.856903577403;5753.367129615739;5765.769369225226;5789.710514474281;5872.265966754156;5596.748146333425;5693.259876216107;5697;5763.366029736726;5764.048931926269;5694.829155125353;5743.483086522233;5770.971145144289;5779.797707080274;5782.3639399666;6248.562600617958;6249;6254.937450625481;6287.748490060392;6349.111124442582;6505.414512693833;6641.169853768279;6655.46756259498;6683.131192944935;6758.357955994222;6666.6333351665635;6719;6725.09211256479;6731.427828634523;6805.081314022593;6247.875042499125;6301;6318.589291696071;6332.556721029484;6334.651594162319;6792.08306878575;6886.380225779704;6912.343327383891;6988.965055174692;7013.684384202723;6446.355364463516;6536.607803531803;6596.670166491752;6610.0745895574655;6617.669116544153;6701.1623547056615;6762.797116086525;6779.186497620317;6790.796276111724;6793.422559082434;6526.184226971629;6528.51036172289;6626.900596491057;6638.933610663831;6735.8989615155815;6636.5354425190735;6773;6782.457403407716;6790.388865002074;6803.557768745016;4878.365812444359;4890.804367825247;4937.259411088294;4939;4943.307936888838;4317.395564620955;4326.113146804897;4348.847790327323;4432.512423633398;4441.911161776747;5771.538276937835;5785.82642520725;5844.094165404369;5844.298684157929;5851.180834683148;6715.966420167965;6735.730570777212;6813;6818.28408017161;6828;5607.43925607436;5711.029125048752;5724.856878577985;5772.134179873053;5827.825165245049;5680.261565996477;5703.058995265735;5732.426757324234;5746.597738158289;5771.509421699118;6534.346565343522;6593.142891424084;6614.7023383947835;6615.76844810439;6619.304972977864;6686.09737685416;6710.832229194211;6719;6799.048133261248;6809.38715515606;6246.187995560548;6508.284088750328;6521.478281737354;6541.443977261795;6610.074589557562;6547.279799221987;6558.737650494118;6630.370114839083;6632.204135503771;6634.237062737847;6672.566283191577;6684.866302674017;6686.264510903894;6795.592264464148;6795.626240556817;6470.352964703492;6482.35176482348;6559.278479367361;6564.409203171546;6564.868702626016;6413.134226879311;6480.092787009917;6508.739650413931;6512.05575191604;6519.282878883224;6482.837929051623;6514.771982980573;6525.706343214471;6526.412622863965;6527.379898909596;6446.580972236789;6505.41451269388;6548.050532672831;6553.049807777941;6647.6676166192465;6266.342034086355;6320.778772742932;6347.873042539216;6355.618662880242;6364.299927007933 18:10:43 INFO - PID 3845 | |1;ext.html;876.536916971946;891.8528442806937;922.6263363337846;924.6624981881607;925.2921515040995;40898.182036359256;41494;41786.58213417872;41809.58190418099;41887.79056104715;38884.416733749014;41973.790131049376;42290.57709422905;42532.78733606335;42628;7989.121196668362;8112.148224436438;8128.471649342774;8138.755837324889;8149.633266503021;7964.163762804909;8083.232092951161;8110.63502142402;8118.02583689958;8120.1879812018915;8135.186481351847;8162.510249385026;8162.959185204096;8165;8177.591120443954;8066.5966701664975;8078.596070196525;8083.393745469084;8096.028476582818;8114.797130071736;7923.762287131395;7979.4015448841565;7993.44045916786;7995.920040799576;8001.079875814297;38708.61291387107;38819;38883.61116388828;38907.22185556301;38945;3471.6007659119273;3497.982510087433;3499.772514786531;3500.089976606075;3500.2124521982605;31683.36633267345;31745.206369840707;31746.206344841565;31780.523292150636;31848;5185.092608793446;5185.429602743695;5187.221916712523;5210;5223.503767142115;183156;186778;191465;192585;232046;49640.503594963586;50088.74955625271;50195.49804501981;50263.74868125636;50315.24527132096;52937;53371;53425.1986220207;53428;53474.46525534695;16293.51119466418;16376.83623163777;16382.672346553003;16399;16424.507264782074;13220;13332.133411328232;13344.933275333562;13345.132566383154;13357.198568085945;14657.926710366593;14718.55844324672;14784.704305913821;14810.333534990736;14883.851161488463;5734.369219385864;5745.626534275342;5801.622894511843;5910.615809972424;5940.663350746134;999.3304485994402;1003.5082809423476;1010.5957616953131;1021.2085633633993;1030.1398332392537;23133.537329253322;23353.76646233516;23481.295561133193;23817;23843.7615623845;1108.733903863067;1116.3525155409843;1123.601121601836;1128.887111288881;1139.3733446604335;12488.12583119173;12585.811212831815;12662.873371266354;12702.555410560772;12711.364431778375;225268;225371;226500;226617;226692;53277.46722532725;53392.46607533875;53555;53557.464425356025;53694;1403.1370707015317;1459.3014279239587;1473.2854565535706;1478.3199728124969;1483.0656686287784;22417.77582224157;23016.194433194756;23160.768392316535;23303.300900972998;23351.299461016195;35091.29817403587;35528.11179720424;35559.28881422305;35571;35573;186454;186603;186963;187211;187361;34712.30575388529;38370.42444363281;38650.42024369581;38803.80598096991;38839.2232155361;46160;47979.5202047989;48096.51903480921;48142;48741.75629121832;34101.65898340985;34223;34429.82785086059;34558.48162277517;34835.12912177216;34193.14517136992;34221.28893555362;34267;34420.65579344174;35143.824280878434;38971.415428769156;39033;39343;39425;39509;33076;33200.1699957503;33447.665523344454;33610;33836.66163338335;24557;24621.87689061615;24652.753472465047;24859;25086.62370064487 18:10:43 INFO - PID 3845 | |2;jquery.html;587.4037851580647;591.7041479260367;593.1073734030284;604.3714536881641;607.5595193484722;22639.886800565993;23407.297781066594;23461.413464663372;23474.178403755872;23490.882545587247;23311.766882331172;24612.138575149875;24735.876320618372;24917.87541062292;24939;6528.7714929977465;6636.668166591675;6697.866042679144;6702.932970670279;6713.227978782429;6351.745930162788;6521.445677117451;6615.17310336208;6628.270890202074;6658.001299805043;6629.171353580802;6644.4684425246105;6656.101426307459;6672.532922695414;6673.199216094075;6664.833379165511;6666.899996500057;6670.399664030237;6676.966615166917;6694.129763130762;6014.218151640259;6186.103015062836;6239.594426362272;6245.531585131133;6260.686965651745;28619.57070643942;28882;29027.854860725773;29028;29080.709192908012;2019.2629690163133;2043.5402034542258;2044.6932960055963;2056.691496275556;2056.7223424837607;18345.357912473;18828.15273312704;19022.52443688905;19077.90461047686;19101.522461938424;2412;2421.092090466075;2449.191766716997;2454.1165180534995;2490.3525083478426;120150;125527;136363;139315;139987.30006350004;14866.182359970306;14953.252337383088;15012.47456339023;15079.924600376928;15202.315895784715;23685.7631423687;23893.402664933517;23908.40228994289;23914.282571522883;23915.76084239135;10228.846567301496;10370.585176593002;10471.214658900613;10475.057244848;10485.842712359321;9595.42427454341;9605.37565058269;9630.32587718853;9643.228541716646;9875.654352097643;8922.86615700765;8980.326475514232;9102.089791020844;9196;9387.859182112274;2683.5169669459683;2690.9058182963895;2759.922686204867;2784.8329100253645;2809.8314101153996;965.2567523007385;968.8352979993418;983.8917719050899;985.3250523391499;989.8020395920845;18792.872427654384;19385.127669254918;19495.707564386546;19785.40643780689;19801.900990494956;1005.9949700251452;1041.6614600254948;1042.7132538551807;1045.4459136657583;1047.8952104789612;10675.83986240207;10681.626143084955;10918.290311129751;11011.504482298316;11057.612983545538;461960;464848;472658;481971;485722;106740.46629766801;107280;111073;111272.44363778291;111643;1024.0936770957755;1079.5250089960393;1100.5102729285552;1155.5089087137933;1190.2620375367328;19298.8070119297;20006.69989950123;20282.492937676678;20399.388018359474;20511.589768204252;55628.72185639046;56269.15596266141;56441;56808;57263.71368143133;67321;67671;68450.3154968444;69987.30012699807;70426.64786676034;14222;14751.188684622019;14822.40710371573;14855.480058198344;14864.925675371554;22778.65832012488;22909.19817806369;22958.77041229633;22962.19632312861;23174;17328.82671173272;18057.638847223243;18502.259909603465;18559.628807424047;18605.534861628566;15964.92017539905;16025.35898564091;16355.754663679816;16443.09562974048;16445;18023.459296221135;18958.336458223897;19017.52456188551;19059.332923347618;19162.425127246206;14027.29863506841;14568.635784105481;14588;14650.63373415673;14667.633309167357;17121.315147393965;17289.913550432168;18166.09169541491;18231.179596918435;18293.451196464128 18:10:43 INFO - PID 3845 | |3;mootools.html;1269.3462866623688;1322.9470821167154;1324.311358093791;1340.9875543964306;1342.8589998050204;15111;15696.529104126881;15715.292811823452;15757.60605984848;15772.605684857885;15417.383304667828;15507.922460387683;15519.456819011337;15539.68920621587;15551.766723499157;2729.986350068257;2757.655293088359;2775.23680987729;2794.8602569871423;2798.160551834452;2833.0650885207833;2845.8577071146465;2854.1865568313033;2855.1434569629027;2858.528342823442;2789.860506974653;2901.5067438535393;2905.186547766629;2906.447774922758;2908.2293192304055;2822.1533539937936;2837.4325134972983;2866.4983627865145;2869.426114777053;2870.411565629041;2755.5040092783292;2809.1572528241445;2809.8454584997817;2820.3795165063652;2862.3416614178855;14705.60296771805;14726.926365368212;14958;15062.774058389134;15073.773893391608;8216.630251638691;8470.407071504997;8477.83044339116;8510.063892971768;8516.063233044422;5588.469095435927;5675.290588676416;5743.885122297573;5756.424357564252;5874.441928016831;2007.9196832126697;2033.095272603692;2050.7231523744404;2076.439361372421;2087.1547023455373;26974.190774276605;27369.04208352698;27388.726112738816;27397.31506712348;27414;6935.653217339213;7024.859502809915;7063.858722825515;7222.494425390172;7467.51461155034;17998.1000949952;18018.279268829363;18048.187831547613;18061.367852125113;18062.5484362892;7870;7890.9605451972375;7906.960465197637;7928.84142317162;8008.79978000543;6814.386705196457;7264.818379540448;7306.963465182746;7401.444891633149;7514.211007844207;5197.272381866542;5438.782448702008;5552.750126244328;5636.60543761941;5692.516136128475;1509.939602415913;1522.3682171898538;1522.824875139351;1525.305985776469;1534.1025500082576;874.2787200559437;888.1917455115813;899.0919171636564;899.6311512279934;909.4406939732149;7577.810554736176;7670.578118203553;7681.423893208032;7694.038245219348;7775.494592851446;722.53396559219;750.2460027672155;752.135044698601;754.1553460124586;759.8252401947583;4617.191991401492;4661.277501987197;4707.199776038014;4724.055188962153;4846;21350.252741153985;21990;22174.88912555427;22312.442188945406;22345.44136396603;21219;22129.114835406726;22448.326550203194;22519.437014074778;22557;10727.302725322828;10905.073068789083;10968.670939871816;10988.285761425323;11071.667849964353;8034.517928924283;8061.314788243065;8252.546109963769;8280.544570048705;8405.36959728035;18234.72647910256;18811.90594047021;19154.904225479342;19275.710864337325;19441;19245.711314330016;19386.072828517958;19582.31461898827;19690.704639430132;19975.900120499304;10943.945280273547;10997.560097596326;11069.5018724156;11074.501647426046;11097.722556936142;13823.239721815396;13966.79049814274;14124.78812817788;14177;14216.786748198578;10526.736831578966;10811.972862578043;10879.456027198767;11128.833067503832;11220.943895280472;10938.890611094106;11108.666739997812;11276.887231127585;11283.943580282374;11394.430278486208;10072.395656260354;10188.286819922534;10198.847017294598;10210;10214.642487512901;7571.865382409209;7598.316151546387;7626.656800444104;7646.65590048438;7657.119431265476;7754.534727916343;7773.494722842997;7858.646360913887;7958.522488650699;8024 18:10:43 INFO - PID 3845 | |4;prototype.html;510.44106703160037;519.2807960974052;535.5367607019928;541.6127468859764;542.278769236915;6126.877462450754;6271.937280627192;6280.968595157018;6293.307736149031;6314.873702525947;6198.388419011056;6208.410201030906;6212.844678883031;6223.813285601428;6236.407541283571;5212.504812042859;5213.374395072597;5213.426523082458;5247.107991641411;5248.947510524884;4887.608991280689;5077.746112694369;5207.609429292818;5222.63441559091;5229.37247530295;4956.107900577895;4957.628177886655;4987.800487980477;4991.32617096691;5004.174311238641;5001.499850014988;5005.774740136702;5012.774425150877;5027.346444962168;5032.270320803463;4403.185410699031;4493.573110554492;4498.865034048984;4522.208613492626;4527.660425468103;6010.257025462611;6073.180120683695;6075.504126946028;6098.390160983866;6113.32753397126;5668.943310566883;5680.971595142039;5689.544836413077;5721.170430287627;5730.255066841284;6494.6103233806125;6496.252930912911;6575.11235983141;6588.439982601484;6596.80209593713;4678.415198100238;4928.088303663833;4938.407391113089;4964.652474326798;4965.237340744056;6572.277049524549;6584.835379115512;6734.360235777643;6869.381755642016;6907.585544867324;4956.876078098005;5016.1723315653235;5024.598032157419;5052.191649336089;5056.418511871108;5007.624428167902;5101.520559037837;5121.590272778169;5126.641135120506;5132.255822905732;5609.401320623684;5658.745356458887;5663.490285874291;5664.20701101846;5672.177534257509;6428.492887773772;6504.414602685781;6507.642079685663;6554.623529058856;6561.573497722633;1737.200887591722;1757.9033153176697;1778.8754787164773;1783.7146056630877;1785.5625371783997;1167.626359564948;1199.4062938845327;1222.1261797814605;1231.384307846077;1238.238483332744;5590.357108932525;5608.803691870765;5641.633293835887;5659.773609055674;5675.537921867657;6437.485001199892;6533.673316334164;6579.907443501546;6592.17597800275;6733.8989915151315;5550.805721799798;5579.330480342385;5636.830895073154;5677.347105082886;5680;3495.7378196635354;3495.7378196635354;3497.615262321143;3506;3511.4732790081694;6297.464715499143;6397.136386587844;6402.775902843378;6445.967770161119;6458.225012998376;6158.969205153946;6550.639714815731;6629.856339787632;6646.468282537386;6651.43462805667;6338.605506788592;6436.487425455108;6436.742530298736;6483.157189565326;6532.118164047888;6543.443807276435;6584.662444832207;6587.604743715392;6617.404433600999;6681.398674119353;6491.610503369813;6516.403481147149;6528.412442880163;6599.802005939638;6613.041109039066;6444.871102577828;6445.355464453517;6449.580777249464;6488.935110649022;6519.3480651936325;4939;4970.8757281068265;4978.626603004862;4979.278004689372;4994.9500504994485;6323.715432805627;6397.392247736458;6447.677616119269;6598.964476340937;6613.5370524062855;6249.406306400884;6383.6808159592765;6397.904031439439;6468.514861385509;6499.512536559682;6378.681065946591;6421.614703117828;6441.420272175526;6448.774292899726;6467.902981455376;6142.232220972379;6240.719167637558;6295.748170073329;6326.525510586633;6361.968190159019;6388.712507937061;6394.712237949397;6398.808035758934;6415.101885736094;6518.02229665535;6106;6126.601770885057;6147.631142131487;6174.320824709189;6272.874542509033 18:10:43 INFO - PID 3845 | |5;yui.html;904.3714618340256;908.8682141089542;937.803061357115;938.4181807279492;952.2857856607546;18965;19970.101345439452;20013.699794503056;20082.799172008275;20094.799052009475;18300.633987320278;18718.71921921172;18901.716474252862;19028.14373353199;19147.42557723267;941.8822647169104;945.5177859291776;947.5499137909507;948.0898337595881;948.170350942925;939.5537119868043;969.1181025267008;977.1108291454777;980.4411485453295;980.7695191629962;1031.144150355207;1031.6079889641926;1037.776877971236;1038.2680210451613;1039.8596189514433;1029.881563620182;1030.5156576409113;1030.9742256443649;1031.1544533482547;1033.3902997231633;714.3499415531841;734.1887214627812;740.1894925057107;744.2371569141673;752.5221484357472;20431.89784051085;20463;20625.5874882503;20651.896740516353;20673.8966305169;21903.561928761337;21926.780732192634;22516.437089072904;22767.658485122738;22781;17500.912495437442;17784.91107544467;17814.198361073784;17856.821431785775;18048.09759512019;3808.1622043150464;3820.3505404081375;3829.349010668193;3832.3485007548784;3833.674137698299;143874;144054.27972860174;145566;145732;146764;16692.74960875588;16935.661286774197;17007.914960425118;17137.657246854993;17187.91406042962;30977;31987.360252794813;32087.037388878372;32096.67903320937;32175.35649287001;8533.359998000175;8700.173483519056;8812.51531165792;8814.955925220462;8819.426737262185;3462.3940810358085;3565.23347480293;3565.358235517632;3568.803715795656;3571.357155711945;5564;5651.491365777099;5669.688167150764;5693.430656934356;5701.344345400332;1036.8237399642078;1052.8789189243182;1075.983860242097;1076.8707755069443;1078.266778590566;673.2224280955504;689.1764341611726;697.0241661673596;709.7551344786041;715.1668306422958;11564.710882227844;11661.708457288636;11725;12188.939055304843;12413.627591172279;663.0716996205351;667.6061123936901;679.5956405938458;683.3542302524068;685.7908337956972;5761.884762304814;5821.330546987151;5830.183774271605;5839.299284085937;5905.143754155709;127762;129072;129472.3526382362;130123;130967;82307.5884620573;106462;106740;114188;115420.42289788497;94926.52536737273;95781.52109239549;95839;96111.51944240234;97245.51377243068;12842.87157128454;13396.330183490982;13403.463861445432;13487;13506.257155856521;42705.5729442714;43717.90705232395;44442.333365000195;45461.09077818359;45571.31643025291;59313.70343148429;59692;59809.70095149496;59952.40047599643;60706.39293607184;17449;17650.382236621655;17782.644347113244;17926;18045.639087218446;19086.80913190906;19208.80791192126;19269.132889019747;19340.129694163516;19405.4178374649;17668.29326826964;17746.733798992766;17845.553861153574;17929.193186306908;18057.097145142954;16728;16781.412650557173;16968.575785604964;16994.405195818086;17017.574560636083;16210.432634857723;16260.51218463448;16346.918265408596;16370;16587.91706041462;12412.56556020535;12456.688582785502;12523.499060037497;12622.936885315881;12668.873311266769;13278.53525126616;13388.397522111334;13397.397117129947;13441.46234150623;13472.191668499921 18:10:43 INFO - PID 3845 | __end_tp_report 18:10:43 INFO - PID 3845 | __start_cc_report 18:10:43 INFO - PID 3845 | _x_x_mozilla_cycle_collect,276 18:10:43 INFO - PID 3845 | __end_cc_report 18:10:43 INFO - PID 3845 | __startTimestamp1506561043222__endTimestamp 18:10:43 INFO - PID 3845 | 18:10:43 INFO - PID 3845 | ------- Summary: start ------- 18:10:43 INFO - PID 3845 | Number of tests: 6 18:10:43 INFO - PID 3845 | 18:10:43 INFO - PID 3845 | [#0] dojo.html Cycles:165 Average:6154.71 Median:6446.36 stddev:766.14 (11.9%) stddev-sans-first:731.86 18:10:43 INFO - PID 3845 | Values: 3170.7 3175.3 3199.3 3217.7 3234.1 6117.7 6303.0 6335.6 6347.6 6350.4 6238.0 6246.0 6284.2 6363.5 6363.9 5760.9 5793.8 5829.6 5836.6 5847.4 5667.6 5742.6 5801.9 5849.5 5860.3 5723.9 5753.4 5765.8 5789.7 5872.3 5596.7 5693.3 5697.0 5763.4 5764.0 5694.8 5743.5 5771.0 5779.8 5782.4 6248.6 6249.0 6254.9 6287.7 6349.1 6505.4 6641.2 6655.5 6683.1 6758.4 6666.6 6719.0 6725.1 6731.4 6805.1 6247.9 6301.0 6318.6 6332.6 6334.7 6792.1 6886.4 6912.3 6989.0 7013.7 6446.4 6536.6 6596.7 6610.1 6617.7 6701.2 6762.8 6779.2 6790.8 6793.4 6526.2 6528.5 6626.9 6638.9 6735.9 6636.5 6773.0 6782.5 6790.4 6803.6 4878.4 4890.8 4937.3 4939.0 4943.3 4317.4 4326.1 4348.8 4432.5 4441.9 5771.5 5785.8 5844.1 5844.3 5851.2 6716.0 6735.7 6813.0 6818.3 6828.0 5607.4 5711.0 5724.9 5772.1 5827.8 5680.3 5703.1 5732.4 5746.6 5771.5 6534.3 6593.1 6614.7 6615.8 6619.3 6686.1 6710.8 6719.0 6799.0 6809.4 6246.2 6508.3 6521.5 6541.4 6610.1 6547.3 6558.7 6630.4 6632.2 6634.2 6672.6 6684.9 6686.3 6795.6 6795.6 6470.4 6482.4 6559.3 6564.4 6564.9 6413.1 6480.1 6508.7 6512.1 6519.3 6482.8 6514.8 6525.7 6526.4 6527.4 6446.6 6505.4 6548.1 6553.0 6647.7 6266.3 6320.8 6347.9 6355.6 6364.3 18:10:43 INFO - PID 3845 | 18:10:43 INFO - PID 3845 | [#1] ext.html Cycles:180 Average:38099.47 Median:35335.97 stddev:52889.85 (149.7%) stddev-sans-first:52964.37 18:10:43 INFO - PID 3845 | Values: 876.5 891.9 922.6 924.7 925.3 40898.2 41494.0 41786.6 41809.6 41887.8 38884.4 41973.8 42290.6 42532.8 42628.0 7989.1 8112.1 8128.5 8138.8 8149.6 7964.2 8083.2 8110.6 8118.0 8120.2 8135.2 8162.5 8163.0 8165.0 8177.6 8066.6 8078.6 8083.4 8096.0 8114.8 7923.8 7979.4 7993.4 7995.9 8001.1 38708.6 38819.0 38883.6 38907.2 38945.0 3471.6 3498.0 3499.8 3500.1 3500.2 31683.4 31745.2 31746.2 31780.5 31848.0 5185.1 5185.4 5187.2 5210.0 5223.5 183156.0 186778.0 191465.0 192585.0 232046.0 49640.5 50088.7 50195.5 50263.7 50315.2 52937.0 53371.0 53425.2 53428.0 53474.5 16293.5 16376.8 16382.7 16399.0 16424.5 13220.0 13332.1 13344.9 13345.1 13357.2 14657.9 14718.6 14784.7 14810.3 14883.9 5734.4 5745.6 5801.6 5910.6 5940.7 999.3 1003.5 1010.6 1021.2 1030.1 23133.5 23353.8 23481.3 23817.0 23843.8 1108.7 1116.4 1123.6 1128.9 1139.4 12488.1 12585.8 12662.9 12702.6 12711.4 225268.0 225371.0 226500.0 226617.0 226692.0 53277.5 53392.5 53555.0 53557.5 53694.0 1403.1 1459.3 1473.3 1478.3 1483.1 22417.8 23016.2 23160.8 23303.3 23351.3 35091.3 35528.1 35559.3 35571.0 35573.0 186454.0 186603.0 186963.0 187211.0 187361.0 34712.3 38370.4 38650.4 38803.8 38839.2 46160.0 47979.5 48096.5 48142.0 48741.8 34101.7 34223.0 34429.8 34558.5 34835.1 34193.1 34221.3 34267.0 34420.7 35143.8 38971.4 39033.0 39343.0 39425.0 39509.0 33076.0 33200.2 33447.7 33610.0 33836.7 24557.0 24621.9 24652.8 24859.0 25086.6 18:10:43 INFO - PID 3845 | 18:10:43 INFO - PID 3845 | [#2] jquery.html Cycles:180 Average:33934.09 Median:23242.88 stddev:79636.42 (342.6%) stddev-sans-first:79820.46 18:10:43 INFO - PID 3845 | Values: 587.4 591.7 593.1 604.4 607.6 22639.9 23407.3 23461.4 23474.2 23490.9 23311.8 24612.1 24735.9 24917.9 24939.0 6528.8 6636.7 6697.9 6702.9 6713.2 6351.7 6521.4 6615.2 6628.3 6658.0 6629.2 6644.5 6656.1 6672.5 6673.2 6664.8 6666.9 6670.4 6677.0 6694.1 6014.2 6186.1 6239.6 6245.5 6260.7 28619.6 28882.0 29027.9 29028.0 29080.7 2019.3 2043.5 2044.7 2056.7 2056.7 18345.4 18828.2 19022.5 19077.9 19101.5 2412.0 2421.1 2449.2 2454.1 2490.4 120150.0 125527.0 136363.0 139315.0 139987.3 14866.2 14953.3 15012.5 15079.9 15202.3 23685.8 23893.4 23908.4 23914.3 23915.8 10228.8 10370.6 10471.2 10475.1 10485.8 9595.4 9605.4 9630.3 9643.2 9875.7 8922.9 8980.3 9102.1 9196.0 9387.9 2683.5 2690.9 2759.9 2784.8 2809.8 965.3 968.8 983.9 985.3 989.8 18792.9 19385.1 19495.7 19785.4 19801.9 1006.0 1041.7 1042.7 1045.4 1047.9 10675.8 10681.6 10918.3 11011.5 11057.6 461960.0 464848.0 472658.0 481971.0 485722.0 106740.5 107280.0 111073.0 111272.4 111643.0 1024.1 1079.5 1100.5 1155.5 1190.3 19298.8 20006.7 20282.5 20399.4 20511.6 55628.7 56269.2 56441.0 56808.0 57263.7 67321.0 67671.0 68450.3 69987.3 70426.6 14222.0 14751.2 14822.4 14855.5 14864.9 22778.7 22909.2 22958.8 22962.2 23174.0 17328.8 18057.6 18502.3 18559.6 18605.5 15964.9 16025.4 16355.8 16443.1 16445.0 18023.5 18958.3 19017.5 19059.3 19162.4 14027.3 14568.6 14588.0 14650.6 14667.6 17121.3 17289.9 18166.1 18231.2 18293.5 18:10:43 INFO - PID 3845 | 18:10:43 INFO - PID 3845 | [#3] mootools.html Cycles:180 Average:9479.14 Median:15084.75 stddev:6831.87 (45.3%) stddev-sans-first:6823.19 18:10:43 INFO - PID 3845 | Values: 1269.3 1322.9 1324.3 1341.0 1342.9 15111.0 15696.5 15715.3 15757.6 15772.6 15417.4 15507.9 15519.5 15539.7 15551.8 2730.0 2757.7 2775.2 2794.9 2798.2 2833.1 2845.9 2854.2 2855.1 2858.5 2789.9 2901.5 2905.2 2906.4 2908.2 2822.2 2837.4 2866.5 2869.4 2870.4 2755.5 2809.2 2809.8 2820.4 2862.3 14705.6 14726.9 14958.0 15062.8 15073.8 8216.6 8470.4 8477.8 8510.1 8516.1 5588.5 5675.3 5743.9 5756.4 5874.4 2007.9 2033.1 2050.7 2076.4 2087.2 26974.2 27369.0 27388.7 27397.3 27414.0 6935.7 7024.9 7063.9 7222.5 7467.5 17998.1 18018.3 18048.2 18061.4 18062.5 7870.0 7891.0 7907.0 7928.8 8008.8 6814.4 7264.8 7307.0 7401.4 7514.2 5197.3 5438.8 5552.8 5636.6 5692.5 1509.9 1522.4 1522.8 1525.3 1534.1 874.3 888.2 899.1 899.6 909.4 7577.8 7670.6 7681.4 7694.0 7775.5 722.5 750.2 752.1 754.2 759.8 4617.2 4661.3 4707.2 4724.1 4846.0 21350.3 21990.0 22174.9 22312.4 22345.4 21219.0 22129.1 22448.3 22519.4 22557.0 10727.3 10905.1 10968.7 10988.3 11071.7 8034.5 8061.3 8252.5 8280.5 8405.4 18234.7 18811.9 19154.9 19275.7 19441.0 19245.7 19386.1 19582.3 19690.7 19975.9 10943.9 10997.6 11069.5 11074.5 11097.7 13823.2 13966.8 14124.8 14177.0 14216.8 10526.7 10812.0 10879.5 11128.8 11220.9 10938.9 11108.7 11276.9 11283.9 11394.4 10072.4 10188.3 10198.8 10210.0 10214.6 7571.9 7598.3 7626.7 7646.7 7657.1 7754.5 7773.5 7858.6 7958.5 8024.0 18:10:43 INFO - PID 3845 | 18:10:43 INFO - PID 3845 | [#4] prototype.html Cycles:180 Average:5459.18 Median:6119.96 stddev:1501.84 (24.5%) stddev-sans-first:1459.40 18:10:43 INFO - PID 3845 | Values: 510.4 519.3 535.5 541.6 542.3 6126.9 6271.9 6281.0 6293.3 6314.9 6198.4 6208.4 6212.8 6223.8 6236.4 5212.5 5213.4 5213.4 5247.1 5248.9 4887.6 5077.7 5207.6 5222.6 5229.4 4956.1 4957.6 4987.8 4991.3 5004.2 5001.5 5005.8 5012.8 5027.3 5032.3 4403.2 4493.6 4498.9 4522.2 4527.7 6010.3 6073.2 6075.5 6098.4 6113.3 5668.9 5681.0 5689.5 5721.2 5730.3 6494.6 6496.3 6575.1 6588.4 6596.8 4678.4 4928.1 4938.4 4964.7 4965.2 6572.3 6584.8 6734.4 6869.4 6907.6 4956.9 5016.2 5024.6 5052.2 5056.4 5007.6 5101.5 5121.6 5126.6 5132.3 5609.4 5658.7 5663.5 5664.2 5672.2 6428.5 6504.4 6507.6 6554.6 6561.6 1737.2 1757.9 1778.9 1783.7 1785.6 1167.6 1199.4 1222.1 1231.4 1238.2 5590.4 5608.8 5641.6 5659.8 5675.5 6437.5 6533.7 6579.9 6592.2 6733.9 5550.8 5579.3 5636.8 5677.3 5680.0 3495.7 3495.7 3497.6 3506.0 3511.5 6297.5 6397.1 6402.8 6446.0 6458.2 6159.0 6550.6 6629.9 6646.5 6651.4 6338.6 6436.5 6436.7 6483.2 6532.1 6543.4 6584.7 6587.6 6617.4 6681.4 6491.6 6516.4 6528.4 6599.8 6613.0 6444.9 6445.4 6449.6 6488.9 6519.3 4939.0 4970.9 4978.6 4979.3 4995.0 6323.7 6397.4 6447.7 6599.0 6613.5 6249.4 6383.7 6397.9 6468.5 6499.5 6378.7 6421.6 6441.4 6448.8 6467.9 6142.2 6240.7 6295.7 6326.5 6362.0 6388.7 6394.7 6398.8 6415.1 6518.0 6106.0 6126.6 6147.6 6174.3 6272.9 18:10:43 INFO - PID 3845 | 18:10:43 INFO - PID 3845 | [#5] yui.html Cycles:180 Average:25088.69 Median:19991.90 stddev:36163.11 (180.9%) stddev-sans-first:36218.96 18:10:43 INFO - PID 3845 | Values: 904.4 908.9 937.8 938.4 952.3 18965.0 19970.1 20013.7 20082.8 20094.8 18300.6 18718.7 18901.7 19028.1 19147.4 941.9 945.5 947.5 948.1 948.2 939.6 969.1 977.1 980.4 980.8 1031.1 1031.6 1037.8 1038.3 1039.9 1029.9 1030.5 1031.0 1031.2 1033.4 714.3 734.2 740.2 744.2 752.5 20431.9 20463.0 20625.6 20651.9 20673.9 21903.6 21926.8 22516.4 22767.7 22781.0 17500.9 17784.9 17814.2 17856.8 18048.1 3808.2 3820.4 3829.3 3832.3 3833.7 143874.0 144054.3 145566.0 145732.0 146764.0 16692.7 16935.7 17007.9 17137.7 17187.9 30977.0 31987.4 32087.0 32096.7 32175.4 8533.4 8700.2 8812.5 8815.0 8819.4 3462.4 3565.2 3565.4 3568.8 3571.4 5564.0 5651.5 5669.7 5693.4 5701.3 1036.8 1052.9 1076.0 1076.9 1078.3 673.2 689.2 697.0 709.8 715.2 11564.7 11661.7 11725.0 12188.9 12413.6 663.1 667.6 679.6 683.4 685.8 5761.9 5821.3 5830.2 5839.3 5905.1 127762.0 129072.0 129472.4 130123.0 130967.0 82307.6 106462.0 106740.0 114188.0 115420.4 94926.5 95781.5 95839.0 96111.5 97245.5 12842.9 13396.3 13403.5 13487.0 13506.3 42705.6 43717.9 44442.3 45461.1 45571.3 59313.7 59692.0 59809.7 59952.4 60706.4 17449.0 17650.4 17782.6 17926.0 18045.6 19086.8 19208.8 19269.1 19340.1 19405.4 17668.3 17746.7 17845.6 17929.2 18057.1 16728.0 16781.4 16968.6 16994.4 17017.6 16210.4 16260.5 16346.9 16370.0 16587.9 12412.6 12456.7 12523.5 12622.9 12668.9 13278.5 13388.4 13397.4 13441.5 13472.2 18:10:43 INFO - PID 3845 | -------- Summary: end -------- 18:10:43 INFO - PID 3845 | 18:10:43 INFO - TEST-INFO | 3845: exit 0 18:10:43 INFO - TEST-OK | dromaeo_css | took 1109985ms 18:10:43 INFO - TEST-START | kraken 18:10:43 INFO - Initialising browser for kraken test... 18:10:43 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57255/getInfo.html -profile /tmp/tmpOJwg6N/profile 18:10:43 INFO - TEST-INFO | started process 4206 (/builds/slave/test/build/application/firefox/firefox http://localhost:57255/getInfo.html) 18:10:49 INFO - TEST-INFO | 4206: exit 0 18:10:49 INFO - Browser initialized. 18:10:49 INFO - Running cycle 1/1 for kraken test... 18:10:49 INFO - Using env: {'DISPLAY': ':0', 18:10:49 INFO - 'HOME': '/home/cltbld', 18:10:49 INFO - 'JSGC_DISABLE_POISONING': '1', 18:10:49 INFO - 'LANG': 'en_US.UTF-8', 18:10:49 INFO - 'LANGUAGE': 'en_US:en', 18:10:49 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:10:49 INFO - 'LOGNAME': 'cltbld', 18:10:49 INFO - 'MAIL': '/var/mail/cltbld', 18:10:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:10:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:10:49 INFO - 'MOZ_CRASHREPORTER': '1', 18:10:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:49 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:10:49 INFO - 'MOZ_NO_REMOTE': '1', 18:10:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:10:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:49 INFO - 'NO_EM_RESTART': '1', 18:10:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:49 INFO - 'PWD': '/builds/slave/test', 18:10:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:10:49 INFO - 'RUST_BACKTRACE': 'full', 18:10:49 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:10:49 INFO - 'SHELL': '/bin/bash', 18:10:49 INFO - 'SHLVL': '1', 18:10:49 INFO - 'TERM': 'linux', 18:10:49 INFO - 'TMOUT': '86400', 18:10:49 INFO - 'USER': 'cltbld', 18:10:49 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397', 18:10:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:49 INFO - '_': '/tools/buildbot/bin/python'} 18:10:49 INFO - TEST-INFO | started process 4442 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOJwg6N/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:10:50 INFO - PID 4442 | 18:10:50 INFO - PID 4442 | (/builds/slave/test/build/application/firefox/firefox:4490): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:10:50 INFO - PID 4442 | 18:11:00 INFO - PID 4442 | RSS: Main: 151977984 18:11:00 INFO - PID 4442 | 18:11:03 INFO - PID 4442 | Cycle 1(1): loaded http://localhost:57255/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57255/tests/kraken/driver.html?testName=audio-beat-detection) 18:11:04 INFO - PID 4442 | RSS: Main: 158019584 18:11:04 INFO - PID 4442 | 18:11:06 INFO - PID 4442 | Cycle 1(1): loaded http://localhost:57255/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57255/tests/kraken/driver.html?testName=audio-dft) 18:11:07 INFO - PID 4442 | RSS: Main: 158183424 18:11:07 INFO - PID 4442 | 18:11:10 INFO - PID 4442 | Cycle 1(1): loaded http://localhost:57255/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57255/tests/kraken/driver.html?testName=audio-fft) 18:11:10 INFO - PID 4442 | RSS: Main: 154836992 18:11:10 INFO - PID 4442 | 18:11:12 INFO - PID 4442 | Cycle 1(1): loaded http://localhost:57255/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57255/tests/kraken/driver.html?testName=audio-oscillator) 18:11:13 INFO - PID 4442 | RSS: Main: 154853376 18:11:13 INFO - PID 4442 | 18:11:15 INFO - PID 4442 | Cycle 1(1): loaded http://localhost:57255/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57255/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:11:16 INFO - PID 4442 | RSS: Main: 155025408 18:11:16 INFO - PID 4442 | 18:11:19 INFO - PID 4442 | Cycle 1(1): loaded http://localhost:57255/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57255/tests/kraken/driver.html?testName=imaging-darkroom) 18:11:20 INFO - PID 4442 | RSS: Main: 157491200 18:11:20 INFO - PID 4442 | 18:11:23 INFO - PID 4442 | Cycle 1(1): loaded http://localhost:57255/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57255/tests/kraken/driver.html?testName=imaging-desaturate) 18:11:24 INFO - PID 4442 | RSS: Main: 158023680 18:11:24 INFO - PID 4442 | 18:11:27 INFO - PID 4442 | Cycle 1(1): loaded http://localhost:57255/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57255/tests/kraken/driver.html?testName=json-parse-financial) 18:11:28 INFO - PID 4442 | RSS: Main: 157544448 18:11:28 INFO - PID 4442 | 18:11:29 INFO - PID 4442 | Cycle 1(1): loaded http://localhost:57255/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57255/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:11:30 INFO - PID 4442 | RSS: Main: 157626368 18:11:30 INFO - PID 4442 | 18:11:31 INFO - PID 4442 | Cycle 1(1): loaded http://localhost:57255/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57255/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:11:32 INFO - PID 4442 | RSS: Main: 154841088 18:11:32 INFO - PID 4442 | 18:11:35 INFO - PID 4442 | Cycle 1(1): loaded http://localhost:57255/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57255/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:11:35 INFO - PID 4442 | RSS: Main: 154972160 18:11:35 INFO - PID 4442 | 18:11:38 INFO - PID 4442 | Cycle 1(1): loaded http://localhost:57255/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57255/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:11:39 INFO - PID 4442 | RSS: Main: 155090944 18:11:39 INFO - PID 4442 | 18:11:42 INFO - PID 4442 | Cycle 1(1): loaded http://localhost:57255/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57255/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:11:42 INFO - PID 4442 | RSS: Main: 155181056 18:11:42 INFO - PID 4442 | 18:11:44 INFO - PID 4442 | Cycle 1(1): loaded http://localhost:57255/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57255/tests/kraken/driver.html?testName=ai-astar) 18:11:44 INFO - PID 4442 | RSS: Main: 156545024 18:11:44 INFO - PID 4442 | 18:11:44 INFO - PID 4442 | __start_tp_report 18:11:44 INFO - PID 4442 | _x_x_mozilla_page_load 18:11:44 INFO - PID 4442 | _x_x_mozilla_page_load_details 18:11:44 INFO - PID 4442 | |i|pagename|runs| 18:11:44 INFO - PID 4442 | |0;ai-astar;108;106;107;107;107;107;110;108;107;106 18:11:44 INFO - PID 4442 | |1;audio-beat-detection;119;118;117;119;121;143;114;122;118;117 18:11:44 INFO - PID 4442 | |2;audio-dft;165;165;165;163;163;164;163;164;168;167 18:11:44 INFO - PID 4442 | |3;audio-fft;73;75;76;73;76;74;75;75;74;74 18:11:44 INFO - PID 4442 | |4;audio-oscillator;82;81;80;80;80;79;79;79;81;79 18:11:44 INFO - PID 4442 | |5;imaging-gaussian-blur;110;115;115;114;114;114;113;115;114;115 18:11:44 INFO - PID 4442 | |6;imaging-darkroom;149;155;149;149;149;150;148;148;151;149 18:11:44 INFO - PID 4442 | |7;imaging-desaturate;94;93;94;94;94;95;94;94;95;94 18:11:44 INFO - PID 4442 | |8;json-parse-financial;51;52;53;51;52;53;54;51;55;52 18:11:44 INFO - PID 4442 | |9;json-stringify-tinderbox;39;37;37;37;36;37;38;36;37;36 18:11:44 INFO - PID 4442 | |10;stanford-crypto-aes;77;74;85;73;73;72;76;73;73;78 18:11:44 INFO - PID 4442 | |11;stanford-crypto-ccm;108;108;108;115;116;113;116;117;118;115 18:11:44 INFO - PID 4442 | |12;stanford-crypto-pbkdf2;161;164;163;167;161;177;167;171;157;169 18:11:44 INFO - PID 4442 | |13;stanford-crypto-sha256-iterative;71;64;64;64;68;68;67;71;66;68 18:11:44 INFO - PID 4442 | __end_tp_report 18:11:44 INFO - PID 4442 | __start_cc_report 18:11:44 INFO - PID 4442 | _x_x_mozilla_cycle_collect,733 18:11:44 INFO - PID 4442 | __end_cc_report 18:11:44 INFO - PID 4442 | __startTimestamp1506561104683__endTimestamp 18:11:44 INFO - PID 4442 | 18:11:44 INFO - PID 4442 | ------- Summary: start ------- 18:11:44 INFO - PID 4442 | Number of tests: 14 18:11:44 INFO - PID 4442 | 18:11:44 INFO - PID 4442 | [#0] ai-astar Cycles:10 Average:107.30 Median:107.00 stddev:1.16 (1.1%) stddev-sans-first:1.20 18:11:44 INFO - PID 4442 | Values: 108.0 106.0 107.0 107.0 107.0 107.0 110.0 108.0 107.0 106.0 18:11:44 INFO - PID 4442 | 18:11:44 INFO - PID 4442 | [#1] audio-beat-detection Cycles:10 Average:120.80 Median:119.00 stddev:8.11 (6.8%) stddev-sans-first:8.57 18:11:44 INFO - PID 4442 | Values: 119.0 118.0 117.0 119.0 121.0 143.0 114.0 122.0 118.0 117.0 18:11:44 INFO - PID 4442 | 18:11:44 INFO - PID 4442 | [#2] audio-dft Cycles:10 Average:164.70 Median:165.00 stddev:1.70 (1.0%) stddev-sans-first:1.80 18:11:44 INFO - PID 4442 | Values: 165.0 165.0 165.0 163.0 163.0 164.0 163.0 164.0 168.0 167.0 18:11:44 INFO - PID 4442 | 18:11:44 INFO - PID 4442 | [#3] audio-fft Cycles:10 Average:74.50 Median:75.00 stddev:1.08 (1.4%) stddev-sans-first:1.00 18:11:44 INFO - PID 4442 | Values: 73.0 75.0 76.0 73.0 76.0 74.0 75.0 75.0 74.0 74.0 18:11:44 INFO - PID 4442 | 18:11:44 INFO - PID 4442 | [#4] audio-oscillator Cycles:10 Average:80.00 Median:80.00 stddev:1.05 (1.3%) stddev-sans-first:0.83 18:11:44 INFO - PID 4442 | Values: 82.0 81.0 80.0 80.0 80.0 79.0 79.0 79.0 81.0 79.0 18:11:44 INFO - PID 4442 | 18:11:44 INFO - PID 4442 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.50 stddev:1.52 (1.3%) stddev-sans-first:0.71 18:11:44 INFO - PID 4442 | Values: 110.0 115.0 115.0 114.0 114.0 114.0 113.0 115.0 114.0 115.0 18:11:44 INFO - PID 4442 | 18:11:44 INFO - PID 4442 | [#6] imaging-darkroom Cycles:10 Average:149.70 Median:149.00 stddev:2.06 (1.4%) stddev-sans-first:2.17 18:11:44 INFO - PID 4442 | Values: 149.0 155.0 149.0 149.0 149.0 150.0 148.0 148.0 151.0 149.0 18:11:44 INFO - PID 4442 | 18:11:44 INFO - PID 4442 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 18:11:44 INFO - PID 4442 | Values: 94.0 93.0 94.0 94.0 94.0 95.0 94.0 94.0 95.0 94.0 18:11:44 INFO - PID 4442 | 18:11:44 INFO - PID 4442 | [#8] json-parse-financial Cycles:10 Average:52.40 Median:52.50 stddev:1.35 (2.6%) stddev-sans-first:1.33 18:11:44 INFO - PID 4442 | Values: 51.0 52.0 53.0 51.0 52.0 53.0 54.0 51.0 55.0 52.0 18:11:44 INFO - PID 4442 | 18:11:44 INFO - PID 4442 | [#9] json-stringify-tinderbox Cycles:10 Average:37.00 Median:37.00 stddev:0.94 (2.5%) stddev-sans-first:0.67 18:11:44 INFO - PID 4442 | Values: 39.0 37.0 37.0 37.0 36.0 37.0 38.0 36.0 37.0 36.0 18:11:44 INFO - PID 4442 | 18:11:44 INFO - PID 4442 | [#10] stanford-crypto-aes Cycles:10 Average:75.40 Median:75.00 stddev:3.92 (5.2%) stddev-sans-first:4.12 18:11:44 INFO - PID 4442 | Values: 77.0 74.0 85.0 73.0 73.0 72.0 76.0 73.0 73.0 78.0 18:11:44 INFO - PID 4442 | 18:11:44 INFO - PID 4442 | [#11] stanford-crypto-ccm Cycles:10 Average:113.40 Median:115.50 stddev:3.95 (3.4%) stddev-sans-first:3.67 18:11:44 INFO - PID 4442 | Values: 108.0 108.0 108.0 115.0 116.0 113.0 116.0 117.0 118.0 115.0 18:11:44 INFO - PID 4442 | 18:11:44 INFO - PID 4442 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.70 Median:167.00 stddev:5.77 (3.5%) stddev-sans-first:5.87 18:11:44 INFO - PID 4442 | Values: 161.0 164.0 163.0 167.0 161.0 177.0 167.0 171.0 157.0 169.0 18:11:44 INFO - PID 4442 | 18:11:44 INFO - PID 4442 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:68.00 stddev:2.64 (3.9%) stddev-sans-first:2.40 18:11:44 INFO - PID 4442 | Values: 71.0 64.0 64.0 64.0 68.0 68.0 67.0 71.0 66.0 68.0 18:11:44 INFO - PID 4442 | -------- Summary: end -------- 18:11:44 INFO - PID 4442 | 18:11:45 INFO - PID 4442 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:11:45 INFO - TEST-INFO | 4442: exit 0 18:11:45 INFO - TEST-OK | kraken | took 61636ms 18:11:45 INFO - SUITE-END | took 1171s 18:11:45 INFO - Completed test suite (00:19:32) 18:11:45 INFO - javascript benchmark 18:11:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8585.598514225007, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3170.746340292777, 3175.2538153533915, 3199.2641692410743, 3217.710406063454, 3234.0782876880094, 6117.66352850593, 6303.0, 6335.556511044223, 6347.587406818554, 6350.364963503647, 6238.0, 6246.0, 6284.1516395286735, 6363.490920726343, 6363.9045414318825, 5760.855978600547, 5793.768249270024, 5829.562782791287, 5836.620619659709, 5847.386024467433, 5667.5749318801045, 5742.569307301948, 5801.912971305413, 5849.473547380735, 5860.326062502802, 5723.856903577403, 5753.367129615739, 5765.769369225226, 5789.710514474281, 5872.265966754156, 5596.748146333425, 5693.259876216107, 5697.0, 5763.366029736726, 5764.048931926269, 5694.829155125353, 5743.483086522233, 5770.971145144289, 5779.797707080274, 5782.3639399666, 6248.562600617958, 6249.0, 6254.937450625481, 6287.748490060392, 6349.111124442582, 6505.414512693833, 6641.169853768279, 6655.46756259498, 6683.131192944935, 6758.357955994222, 6666.6333351665635, 6719.0, 6725.09211256479, 6731.427828634523, 6805.081314022593, 6247.875042499125, 6301.0, 6318.589291696071, 6332.556721029484, 6334.651594162319, 6792.08306878575, 6886.380225779704, 6912.343327383891, 6988.965055174692, 7013.684384202723, 6446.355364463516, 6536.607803531803, 6596.670166491752, 6610.0745895574655, 6617.669116544153, 6701.1623547056615, 6762.797116086525, 6779.186497620317, 6790.796276111724, 6793.422559082434, 6526.184226971629, 6528.51036172289, 6626.900596491057, 6638.933610663831, 6735.8989615155815, 6636.5354425190735, 6773.0, 6782.457403407716, 6790.388865002074, 6803.557768745016, 4878.365812444359, 4890.804367825247, 4937.259411088294, 4939.0, 4943.307936888838, 4317.395564620955, 4326.113146804897, 4348.847790327323, 4432.512423633398, 4441.911161776747, 5771.538276937835, 5785.82642520725, 5844.094165404369, 5844.298684157929, 5851.180834683148, 6715.966420167965, 6735.730570777212, 6813.0, 6818.28408017161, 6828.0, 5607.43925607436, 5711.029125048752, 5724.856878577985, 5772.134179873053, 5827.825165245049, 5680.261565996477, 5703.058995265735, 5732.426757324234, 5746.597738158289, 5771.509421699118, 6534.346565343522, 6593.142891424084, 6614.7023383947835, 6615.76844810439, 6619.304972977864, 6686.09737685416, 6710.832229194211, 6719.0, 6799.048133261248, 6809.38715515606, 6246.187995560548, 6508.284088750328, 6521.478281737354, 6541.443977261795, 6610.074589557562, 6547.279799221987, 6558.737650494118, 6630.370114839083, 6632.204135503771, 6634.237062737847, 6672.566283191577, 6684.866302674017, 6686.264510903894, 6795.592264464148, 6795.626240556817, 6470.352964703492, 6482.35176482348, 6559.278479367361, 6564.409203171546, 6564.868702626016, 6413.134226879311, 6480.092787009917, 6508.739650413931, 6512.05575191604, 6519.282878883224, 6482.837929051623, 6514.771982980573, 6525.706343214471, 6526.412622863965, 6527.379898909596, 6446.580972236789, 6505.41451269388, 6548.050532672831, 6553.049807777941, 6647.6676166192465, 6266.342034086355, 6320.778772742932, 6347.873042539216, 6355.618662880242, 6364.299927007933], "value": 6094.849137173704, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [876.536916971946, 891.8528442806937, 922.6263363337846, 924.6624981881607, 925.2921515040995, 40898.182036359256, 41494.0, 41786.58213417872, 41809.58190418099, 41887.79056104715, 38884.416733749014, 41973.790131049376, 42290.57709422905, 42532.78733606335, 42628.0, 7989.121196668362, 8112.148224436438, 8128.471649342774, 8138.755837324889, 8149.633266503021, 7964.163762804909, 8083.232092951161, 8110.63502142402, 8118.02583689958, 8120.1879812018915, 8135.186481351847, 8162.510249385026, 8162.959185204096, 8165.0, 8177.591120443954, 8066.5966701664975, 8078.596070196525, 8083.393745469084, 8096.028476582818, 8114.797130071736, 7923.762287131395, 7979.4015448841565, 7993.44045916786, 7995.920040799576, 8001.079875814297, 38708.61291387107, 38819.0, 38883.61116388828, 38907.22185556301, 38945.0, 3471.6007659119273, 3497.982510087433, 3499.772514786531, 3500.089976606075, 3500.2124521982605, 31683.36633267345, 31745.206369840707, 31746.206344841565, 31780.523292150636, 31848.0, 5185.092608793446, 5185.429602743695, 5187.221916712523, 5210.0, 5223.503767142115, 183156.0, 186778.0, 191465.0, 192585.0, 232046.0, 49640.503594963586, 50088.74955625271, 50195.49804501981, 50263.74868125636, 50315.24527132096, 52937.0, 53371.0, 53425.1986220207, 53428.0, 53474.46525534695, 16293.51119466418, 16376.83623163777, 16382.672346553003, 16399.0, 16424.507264782074, 13220.0, 13332.133411328232, 13344.933275333562, 13345.132566383154, 13357.198568085945, 14657.926710366593, 14718.55844324672, 14784.704305913821, 14810.333534990736, 14883.851161488463, 5734.369219385864, 5745.626534275342, 5801.622894511843, 5910.615809972424, 5940.663350746134, 999.3304485994402, 1003.5082809423476, 1010.5957616953131, 1021.2085633633993, 1030.1398332392537, 23133.537329253322, 23353.76646233516, 23481.295561133193, 23817.0, 23843.7615623845, 1108.733903863067, 1116.3525155409843, 1123.601121601836, 1128.887111288881, 1139.3733446604335, 12488.12583119173, 12585.811212831815, 12662.873371266354, 12702.555410560772, 12711.364431778375, 225268.0, 225371.0, 226500.0, 226617.0, 226692.0, 53277.46722532725, 53392.46607533875, 53555.0, 53557.464425356025, 53694.0, 1403.1370707015317, 1459.3014279239587, 1473.2854565535706, 1478.3199728124969, 1483.0656686287784, 22417.77582224157, 23016.194433194756, 23160.768392316535, 23303.300900972998, 23351.299461016195, 35091.29817403587, 35528.11179720424, 35559.28881422305, 35571.0, 35573.0, 186454.0, 186603.0, 186963.0, 187211.0, 187361.0, 34712.30575388529, 38370.42444363281, 38650.42024369581, 38803.80598096991, 38839.2232155361, 46160.0, 47979.5202047989, 48096.51903480921, 48142.0, 48741.75629121832, 34101.65898340985, 34223.0, 34429.82785086059, 34558.48162277517, 34835.12912177216, 34193.14517136992, 34221.28893555362, 34267.0, 34420.65579344174, 35143.824280878434, 38971.415428769156, 39033.0, 39343.0, 39425.0, 39509.0, 33076.0, 33200.1699957503, 33447.665523344454, 33610.0, 33836.66163338335, 24557.0, 24621.87689061615, 24652.753472465047, 24859.0, 25086.62370064487], "value": 17652.978087221254, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.4037851580647, 591.7041479260367, 593.1073734030284, 604.3714536881641, 607.5595193484722, 22639.886800565993, 23407.297781066594, 23461.413464663372, 23474.178403755872, 23490.882545587247, 23311.766882331172, 24612.138575149875, 24735.876320618372, 24917.87541062292, 24939.0, 6528.7714929977465, 6636.668166591675, 6697.866042679144, 6702.932970670279, 6713.227978782429, 6351.745930162788, 6521.445677117451, 6615.17310336208, 6628.270890202074, 6658.001299805043, 6629.171353580802, 6644.4684425246105, 6656.101426307459, 6672.532922695414, 6673.199216094075, 6664.833379165511, 6666.899996500057, 6670.399664030237, 6676.966615166917, 6694.129763130762, 6014.218151640259, 6186.103015062836, 6239.594426362272, 6245.531585131133, 6260.686965651745, 28619.57070643942, 28882.0, 29027.854860725773, 29028.0, 29080.709192908012, 2019.2629690163133, 2043.5402034542258, 2044.6932960055963, 2056.691496275556, 2056.7223424837607, 18345.357912473, 18828.15273312704, 19022.52443688905, 19077.90461047686, 19101.522461938424, 2412.0, 2421.092090466075, 2449.191766716997, 2454.1165180534995, 2490.3525083478426, 120150.0, 125527.0, 136363.0, 139315.0, 139987.30006350004, 14866.182359970306, 14953.252337383088, 15012.47456339023, 15079.924600376928, 15202.315895784715, 23685.7631423687, 23893.402664933517, 23908.40228994289, 23914.282571522883, 23915.76084239135, 10228.846567301496, 10370.585176593002, 10471.214658900613, 10475.057244848, 10485.842712359321, 9595.42427454341, 9605.37565058269, 9630.32587718853, 9643.228541716646, 9875.654352097643, 8922.86615700765, 8980.326475514232, 9102.089791020844, 9196.0, 9387.859182112274, 2683.5169669459683, 2690.9058182963895, 2759.922686204867, 2784.8329100253645, 2809.8314101153996, 965.2567523007385, 968.8352979993418, 983.8917719050899, 985.3250523391499, 989.8020395920845, 18792.872427654384, 19385.127669254918, 19495.707564386546, 19785.40643780689, 19801.900990494956, 1005.9949700251452, 1041.6614600254948, 1042.7132538551807, 1045.4459136657583, 1047.8952104789612, 10675.83986240207, 10681.626143084955, 10918.290311129751, 11011.504482298316, 11057.612983545538, 461960.0, 464848.0, 472658.0, 481971.0, 485722.0, 106740.46629766801, 107280.0, 111073.0, 111272.44363778291, 111643.0, 1024.0936770957755, 1079.5250089960393, 1100.5102729285552, 1155.5089087137933, 1190.2620375367328, 19298.8070119297, 20006.69989950123, 20282.492937676678, 20399.388018359474, 20511.589768204252, 55628.72185639046, 56269.15596266141, 56441.0, 56808.0, 57263.71368143133, 67321.0, 67671.0, 68450.3154968444, 69987.30012699807, 70426.64786676034, 14222.0, 14751.188684622019, 14822.40710371573, 14855.480058198344, 14864.925675371554, 22778.65832012488, 22909.19817806369, 22958.77041229633, 22962.19632312861, 23174.0, 17328.82671173272, 18057.638847223243, 18502.259909603465, 18559.628807424047, 18605.534861628566, 15964.92017539905, 16025.35898564091, 16355.754663679816, 16443.09562974048, 16445.0, 18023.459296221135, 18958.336458223897, 19017.52456188551, 19059.332923347618, 19162.425127246206, 14027.29863506841, 14568.635784105481, 14588.0, 14650.63373415673, 14667.633309167357, 17121.315147393965, 17289.913550432168, 18166.09169541491, 18231.179596918435, 18293.451196464128], "value": 11959.708818864201, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1269.3462866623688, 1322.9470821167154, 1324.311358093791, 1340.9875543964306, 1342.8589998050204, 15111.0, 15696.529104126881, 15715.292811823452, 15757.60605984848, 15772.605684857885, 15417.383304667828, 15507.922460387683, 15519.456819011337, 15539.68920621587, 15551.766723499157, 2729.986350068257, 2757.655293088359, 2775.23680987729, 2794.8602569871423, 2798.160551834452, 2833.0650885207833, 2845.8577071146465, 2854.1865568313033, 2855.1434569629027, 2858.528342823442, 2789.860506974653, 2901.5067438535393, 2905.186547766629, 2906.447774922758, 2908.2293192304055, 2822.1533539937936, 2837.4325134972983, 2866.4983627865145, 2869.426114777053, 2870.411565629041, 2755.5040092783292, 2809.1572528241445, 2809.8454584997817, 2820.3795165063652, 2862.3416614178855, 14705.60296771805, 14726.926365368212, 14958.0, 15062.774058389134, 15073.773893391608, 8216.630251638691, 8470.407071504997, 8477.83044339116, 8510.063892971768, 8516.063233044422, 5588.469095435927, 5675.290588676416, 5743.885122297573, 5756.424357564252, 5874.441928016831, 2007.9196832126697, 2033.095272603692, 2050.7231523744404, 2076.439361372421, 2087.1547023455373, 26974.190774276605, 27369.04208352698, 27388.726112738816, 27397.31506712348, 27414.0, 6935.653217339213, 7024.859502809915, 7063.858722825515, 7222.494425390172, 7467.51461155034, 17998.1000949952, 18018.279268829363, 18048.187831547613, 18061.367852125113, 18062.5484362892, 7870.0, 7890.9605451972375, 7906.960465197637, 7928.84142317162, 8008.79978000543, 6814.386705196457, 7264.818379540448, 7306.963465182746, 7401.444891633149, 7514.211007844207, 5197.272381866542, 5438.782448702008, 5552.750126244328, 5636.60543761941, 5692.516136128475, 1509.939602415913, 1522.3682171898538, 1522.824875139351, 1525.305985776469, 1534.1025500082576, 874.2787200559437, 888.1917455115813, 899.0919171636564, 899.6311512279934, 909.4406939732149, 7577.810554736176, 7670.578118203553, 7681.423893208032, 7694.038245219348, 7775.494592851446, 722.53396559219, 750.2460027672155, 752.135044698601, 754.1553460124586, 759.8252401947583, 4617.191991401492, 4661.277501987197, 4707.199776038014, 4724.055188962153, 4846.0, 21350.252741153985, 21990.0, 22174.88912555427, 22312.442188945406, 22345.44136396603, 21219.0, 22129.114835406726, 22448.326550203194, 22519.437014074778, 22557.0, 10727.302725322828, 10905.073068789083, 10968.670939871816, 10988.285761425323, 11071.667849964353, 8034.517928924283, 8061.314788243065, 8252.546109963769, 8280.544570048705, 8405.36959728035, 18234.72647910256, 18811.90594047021, 19154.904225479342, 19275.710864337325, 19441.0, 19245.711314330016, 19386.072828517958, 19582.31461898827, 19690.704639430132, 19975.900120499304, 10943.945280273547, 10997.560097596326, 11069.5018724156, 11074.501647426046, 11097.722556936142, 13823.239721815396, 13966.79049814274, 14124.78812817788, 14177.0, 14216.786748198578, 10526.736831578966, 10811.972862578043, 10879.456027198767, 11128.833067503832, 11220.943895280472, 10938.890611094106, 11108.666739997812, 11276.887231127585, 11283.943580282374, 11394.430278486208, 10072.395656260354, 10188.286819922534, 10198.847017294598, 10210.0, 10214.642487512901, 7571.865382409209, 7598.316151546387, 7626.656800444104, 7646.65590048438, 7657.119431265476, 7754.534727916343, 7773.494722842997, 7858.646360913887, 7958.522488650699, 8024.0], "value": 6737.456444296699, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.44106703160037, 519.2807960974052, 535.5367607019928, 541.6127468859764, 542.278769236915, 6126.877462450754, 6271.937280627192, 6280.968595157018, 6293.307736149031, 6314.873702525947, 6198.388419011056, 6208.410201030906, 6212.844678883031, 6223.813285601428, 6236.407541283571, 5212.504812042859, 5213.374395072597, 5213.426523082458, 5247.107991641411, 5248.947510524884, 4887.608991280689, 5077.746112694369, 5207.609429292818, 5222.63441559091, 5229.37247530295, 4956.107900577895, 4957.628177886655, 4987.800487980477, 4991.32617096691, 5004.174311238641, 5001.499850014988, 5005.774740136702, 5012.774425150877, 5027.346444962168, 5032.270320803463, 4403.185410699031, 4493.573110554492, 4498.865034048984, 4522.208613492626, 4527.660425468103, 6010.257025462611, 6073.180120683695, 6075.504126946028, 6098.390160983866, 6113.32753397126, 5668.943310566883, 5680.971595142039, 5689.544836413077, 5721.170430287627, 5730.255066841284, 6494.6103233806125, 6496.252930912911, 6575.11235983141, 6588.439982601484, 6596.80209593713, 4678.415198100238, 4928.088303663833, 4938.407391113089, 4964.652474326798, 4965.237340744056, 6572.277049524549, 6584.835379115512, 6734.360235777643, 6869.381755642016, 6907.585544867324, 4956.876078098005, 5016.1723315653235, 5024.598032157419, 5052.191649336089, 5056.418511871108, 5007.624428167902, 5101.520559037837, 5121.590272778169, 5126.641135120506, 5132.255822905732, 5609.401320623684, 5658.745356458887, 5663.490285874291, 5664.20701101846, 5672.177534257509, 6428.492887773772, 6504.414602685781, 6507.642079685663, 6554.623529058856, 6561.573497722633, 1737.200887591722, 1757.9033153176697, 1778.8754787164773, 1783.7146056630877, 1785.5625371783997, 1167.626359564948, 1199.4062938845327, 1222.1261797814605, 1231.384307846077, 1238.238483332744, 5590.357108932525, 5608.803691870765, 5641.633293835887, 5659.773609055674, 5675.537921867657, 6437.485001199892, 6533.673316334164, 6579.907443501546, 6592.17597800275, 6733.8989915151315, 5550.805721799798, 5579.330480342385, 5636.830895073154, 5677.347105082886, 5680.0, 3495.7378196635354, 3495.7378196635354, 3497.615262321143, 3506.0, 3511.4732790081694, 6297.464715499143, 6397.136386587844, 6402.775902843378, 6445.967770161119, 6458.225012998376, 6158.969205153946, 6550.639714815731, 6629.856339787632, 6646.468282537386, 6651.43462805667, 6338.605506788592, 6436.487425455108, 6436.742530298736, 6483.157189565326, 6532.118164047888, 6543.443807276435, 6584.662444832207, 6587.604743715392, 6617.404433600999, 6681.398674119353, 6491.610503369813, 6516.403481147149, 6528.412442880163, 6599.802005939638, 6613.041109039066, 6444.871102577828, 6445.355464453517, 6449.580777249464, 6488.935110649022, 6519.3480651936325, 4939.0, 4970.8757281068265, 4978.626603004862, 4979.278004689372, 4994.9500504994485, 6323.715432805627, 6397.392247736458, 6447.677616119269, 6598.964476340937, 6613.5370524062855, 6249.406306400884, 6383.6808159592765, 6397.904031439439, 6468.514861385509, 6499.512536559682, 6378.681065946591, 6421.614703117828, 6441.420272175526, 6448.774292899726, 6467.902981455376, 6142.232220972379, 6240.719167637558, 6295.748170073329, 6326.525510586633, 6361.968190159019, 6388.712507937061, 6394.712237949397, 6398.808035758934, 6415.101885736094, 6518.02229665535, 6106.0, 6126.601770885057, 6147.631142131487, 6174.320824709189, 6272.874542509033], "value": 5021.201213072842, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [904.3714618340256, 908.8682141089542, 937.803061357115, 938.4181807279492, 952.2857856607546, 18965.0, 19970.101345439452, 20013.699794503056, 20082.799172008275, 20094.799052009475, 18300.633987320278, 18718.71921921172, 18901.716474252862, 19028.14373353199, 19147.42557723267, 941.8822647169104, 945.5177859291776, 947.5499137909507, 948.0898337595881, 948.170350942925, 939.5537119868043, 969.1181025267008, 977.1108291454777, 980.4411485453295, 980.7695191629962, 1031.144150355207, 1031.6079889641926, 1037.776877971236, 1038.2680210451613, 1039.8596189514433, 1029.881563620182, 1030.5156576409113, 1030.9742256443649, 1031.1544533482547, 1033.3902997231633, 714.3499415531841, 734.1887214627812, 740.1894925057107, 744.2371569141673, 752.5221484357472, 20431.89784051085, 20463.0, 20625.5874882503, 20651.896740516353, 20673.8966305169, 21903.561928761337, 21926.780732192634, 22516.437089072904, 22767.658485122738, 22781.0, 17500.912495437442, 17784.91107544467, 17814.198361073784, 17856.821431785775, 18048.09759512019, 3808.1622043150464, 3820.3505404081375, 3829.349010668193, 3832.3485007548784, 3833.674137698299, 143874.0, 144054.27972860174, 145566.0, 145732.0, 146764.0, 16692.74960875588, 16935.661286774197, 17007.914960425118, 17137.657246854993, 17187.91406042962, 30977.0, 31987.360252794813, 32087.037388878372, 32096.67903320937, 32175.35649287001, 8533.359998000175, 8700.173483519056, 8812.51531165792, 8814.955925220462, 8819.426737262185, 3462.3940810358085, 3565.23347480293, 3565.358235517632, 3568.803715795656, 3571.357155711945, 5564.0, 5651.491365777099, 5669.688167150764, 5693.430656934356, 5701.344345400332, 1036.8237399642078, 1052.8789189243182, 1075.983860242097, 1076.8707755069443, 1078.266778590566, 673.2224280955504, 689.1764341611726, 697.0241661673596, 709.7551344786041, 715.1668306422958, 11564.710882227844, 11661.708457288636, 11725.0, 12188.939055304843, 12413.627591172279, 663.0716996205351, 667.6061123936901, 679.5956405938458, 683.3542302524068, 685.7908337956972, 5761.884762304814, 5821.330546987151, 5830.183774271605, 5839.299284085937, 5905.143754155709, 127762.0, 129072.0, 129472.3526382362, 130123.0, 130967.0, 82307.5884620573, 106462.0, 106740.0, 114188.0, 115420.42289788497, 94926.52536737273, 95781.52109239549, 95839.0, 96111.51944240234, 97245.51377243068, 12842.87157128454, 13396.330183490982, 13403.463861445432, 13487.0, 13506.257155856521, 42705.5729442714, 43717.90705232395, 44442.333365000195, 45461.09077818359, 45571.31643025291, 59313.70343148429, 59692.0, 59809.70095149496, 59952.40047599643, 60706.39293607184, 17449.0, 17650.382236621655, 17782.644347113244, 17926.0, 18045.639087218446, 19086.80913190906, 19208.80791192126, 19269.132889019747, 19340.129694163516, 19405.4178374649, 17668.29326826964, 17746.733798992766, 17845.553861153574, 17929.193186306908, 18057.097145142954, 16728.0, 16781.412650557173, 16968.575785604964, 16994.405195818086, 17017.574560636083, 16210.432634857723, 16260.51218463448, 16346.918265408596, 16370.0, 16587.91706041462, 12412.56556020535, 12456.688582785502, 12523.499060037497, 12622.936885315881, 12668.873311266769, 13278.53525126616, 13388.397522111334, 13397.397117129947, 13441.46234150623, 13472.191668499921], "value": 9200.077661004507, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1416.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 106.0, 107.0, 107.0, 107.0, 107.0, 110.0, 108.0, 107.0, 106.0], "value": 107.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 118.0, 117.0, 119.0, 121.0, 143.0, 114.0, 122.0, 118.0, 117.0], "value": 120.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 165.0, 163.0, 163.0, 164.0, 163.0, 164.0, 168.0, 167.0], "value": 164.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 76.0, 73.0, 76.0, 74.0, 75.0, 75.0, 74.0, 74.0], "value": 74.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 80.0, 80.0, 80.0, 79.0, 79.0, 79.0, 81.0, 79.0], "value": 80.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 115.0, 115.0, 114.0, 114.0, 114.0, 113.0, 115.0, 114.0, 115.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 155.0, 149.0, 149.0, 149.0, 150.0, 148.0, 148.0, 151.0, 149.0], "value": 149.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 53.0, 51.0, 52.0, 53.0, 54.0, 51.0, 55.0, 52.0], "value": 52.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 37.0, 37.0, 37.0, 36.0, 37.0, 38.0, 36.0, 37.0, 36.0], "value": 37.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 85.0, 73.0, 73.0, 72.0, 76.0, 73.0, 73.0, 78.0], "value": 75.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 108.0, 108.0, 115.0, 116.0, 113.0, 116.0, 117.0, 118.0, 115.0], "value": 113.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 164.0, 163.0, 167.0, 161.0, 177.0, 167.0, 171.0, 157.0, 169.0], "value": 165.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 64.0, 64.0, 64.0, 68.0, 68.0, 67.0, 71.0, 66.0, 68.0], "value": 67.1, "unit": "score"}]}]} 18:11:45 INFO - Return code: 0 18:11:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:11:45 INFO - # TBPL SUCCESS # 18:11:45 INFO - Running post-action listener: _package_coverage_data 18:11:45 INFO - Running post-action listener: _resource_record_post_action 18:11:45 INFO - [mozharness: 2017-09-28 01:11:45.946000Z] Finished run-tests step (success) 18:11:45 INFO - Running post-run listener: _resource_record_post_run 18:11:46 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 10846208; Write bytes: 458129408; Read time: 2392; Write time: 403920 18:11:46 INFO - TinderboxPrint: CPU usage
13.1% 18:11:46 INFO - TinderboxPrint: I/O read bytes / time
10,846,208 / 2,392 18:11:46 INFO - TinderboxPrint: I/O write bytes / time
458,129,408 / 403,920 18:11:46 INFO - TinderboxPrint: CPU idle
8,239.1 (86.8%) 18:11:46 INFO - TinderboxPrint: CPU user
1,194.1 (12.6%) 18:11:46 INFO - TinderboxPrint: Swap in / out
0 / 0 18:11:46 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 8192; Write bytes: 165404672; Read time: 300; Write time: 194116 18:11:46 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:11:46 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 8335360; Write bytes: 292724736; Read time: 1752; Write time: 209804 18:11:46 INFO - Running post-run listener: _upload_blobber_files 18:11:46 INFO - Blob upload gear active. 18:11:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:11:46 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 18:11:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:11:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:11:46 INFO - (blobuploader) - INFO - Open directory for files ... 18:11:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:11:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:11:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:11:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:11:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:11:47 INFO - (blobuploader) - INFO - Done attempting. 18:11:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:11:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:11:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:11:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:11:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:11:54 INFO - (blobuploader) - INFO - Done attempting. 18:11:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:11:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:11:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:11:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:11:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:11:55 INFO - (blobuploader) - INFO - Done attempting. 18:11:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:11:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:11:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:11:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:11:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:11:56 INFO - (blobuploader) - INFO - Done attempting. 18:11:56 INFO - (blobuploader) - INFO - Iteration through files over. 18:11:56 INFO - Return code: 0 18:11:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:11:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:11:56 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f00acb625b903ee12cb2d3909d2405ed74ae4f8bd9e87a9bd69d9d4b169b80279d5028de9ceb13f43481e0b173de5f060732c6b8c61e44bcac3d38cf1a6ac6de", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/578bba156fa6ce140db4755db792249377d48fc47bfb598cbd85b05922e6ceaa566b01a737182f0018a70b67a2af3a8035f163895aedc41d5570fd4c80751ba5", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6554b3abec3e390daf8c71fef56086e05845b6d094a7f357535cb2df82e4f8108369efda90b6635d1f0b971be0a9cec26cd66722deec6d3f4c97de2b1f520613", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/39c0998ea42c1d4ad0ce32a24fa7e9f3a549f34f788d222288c5631416818c6ef193a72a5ed119df4408908068c203e7e05cb2094dbd6f5e5eb96668a545b3c9"} 18:11:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:11:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:11:56 INFO - Contents: 18:11:56 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f00acb625b903ee12cb2d3909d2405ed74ae4f8bd9e87a9bd69d9d4b169b80279d5028de9ceb13f43481e0b173de5f060732c6b8c61e44bcac3d38cf1a6ac6de", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/578bba156fa6ce140db4755db792249377d48fc47bfb598cbd85b05922e6ceaa566b01a737182f0018a70b67a2af3a8035f163895aedc41d5570fd4c80751ba5", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6554b3abec3e390daf8c71fef56086e05845b6d094a7f357535cb2df82e4f8108369efda90b6635d1f0b971be0a9cec26cd66722deec6d3f4c97de2b1f520613", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/39c0998ea42c1d4ad0ce32a24fa7e9f3a549f34f788d222288c5631416818c6ef193a72a5ed119df4408908068c203e7e05cb2094dbd6f5e5eb96668a545b3c9"} 18:11:56 INFO - Running post-run listener: copy_logs_to_upload_dir 18:11:56 INFO - Copying logs to upload dir... 18:11:56 INFO - mkdir: /builds/slave/test/build/upload/logs 18:11:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.585347 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2017-09-27 18:11:56.951464) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 18:11:56.955897) ========= 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=1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f00acb625b903ee12cb2d3909d2405ed74ae4f8bd9e87a9bd69d9d4b169b80279d5028de9ceb13f43481e0b173de5f060732c6b8c61e44bcac3d38cf1a6ac6de", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/578bba156fa6ce140db4755db792249377d48fc47bfb598cbd85b05922e6ceaa566b01a737182f0018a70b67a2af3a8035f163895aedc41d5570fd4c80751ba5", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6554b3abec3e390daf8c71fef56086e05845b6d094a7f357535cb2df82e4f8108369efda90b6635d1f0b971be0a9cec26cd66722deec6d3f4c97de2b1f520613", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/39c0998ea42c1d4ad0ce32a24fa7e9f3a549f34f788d222288c5631416818c6ef193a72a5ed119df4408908068c203e7e05cb2094dbd6f5e5eb96668a545b3c9"} build_url:https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011378 build_url: 'https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f00acb625b903ee12cb2d3909d2405ed74ae4f8bd9e87a9bd69d9d4b169b80279d5028de9ceb13f43481e0b173de5f060732c6b8c61e44bcac3d38cf1a6ac6de", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/578bba156fa6ce140db4755db792249377d48fc47bfb598cbd85b05922e6ceaa566b01a737182f0018a70b67a2af3a8035f163895aedc41d5570fd4c80751ba5", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6554b3abec3e390daf8c71fef56086e05845b6d094a7f357535cb2df82e4f8108369efda90b6635d1f0b971be0a9cec26cd66722deec6d3f4c97de2b1f520613", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/39c0998ea42c1d4ad0ce32a24fa7e9f3a549f34f788d222288c5631416818c6ef193a72a5ed119df4408908068c203e7e05cb2094dbd6f5e5eb96668a545b3c9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 18:11:56.989175) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 18:11:56.989519) ========= 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=1f5564757af27664ca02d71f000001b0-1506559871.441805-1960530397 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004432 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 18:11:57.041304) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 18:11:57.041613) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 18:11:57.041904) ========= ========= Total master_lag: 0.21 =========