builder: mozilla-central_ubuntu64_hw_test-dromaeojs-stylo-disabled-e10s-pgo slave: talos-linux64-ix-027 starttime: 1505958486.1 results: success (0) revision: f8dd3f21e434be32fe5901849f1723b0e64cf668 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-20 18:48:06.102212) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-20 18:48:06.102677) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-20 18:48:06.102957) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-20 18:48:06.123007) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:48:06.123380) ========= 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-1505958484.852817-104608235 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-20 18:48:06-- 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% 24.0M=0s 2017-09-20 18:48:06 (24.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127823 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:48:06.268353) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:48:06.271784) ========= 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-1505958484.852817-104608235 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019997 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:48:06.324725) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:48:06.325084) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f8dd3f21e434be32fe5901849f1723b0e64cf668 --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 f8dd3f21e434be32fe5901849f1723b0e64cf668 --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-1505958484.852817-104608235 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-20 18:48:06,270 truncating revision to first 12 chars 2017-09-20 18:48:06,270 Setting DEBUG logging. 2017-09-20 18:48:06,270 attempt 1/10 2017-09-20 18:48:06,270 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f8dd3f21e434?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-20 18:48:06,782 unpacking tar archive at: mozilla-central-f8dd3f21e434/testing/mozharness/ program finished with exit code 0 elapsedTime=0.796237 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:48:07.140800) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-20 18:48:07.141201) ========= script_repo_revision: f8dd3f21e434be32fe5901849f1723b0e64cf668 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-20 18:48:07.141663) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-20 18:48:07.141945) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-20 18:48:07.161489) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2017-09-20 18:48:07.162036) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-stylo-disabled-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1f5564757af27664ca02d71f000001b0-1505958484.852817-104608235 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:48:07 INFO - MultiFileLogger online at 20170920 18:48:07 in /builds/slave/test 18:48:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:48:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:48:07 INFO - {'append_to_log': False, 18:48:07 INFO - 'base_work_dir': '/builds/slave/test', 18:48:07 INFO - 'blob_upload_branch': 'Firefox', 18:48:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:48:07 INFO - 'branch': 'Firefox', 18:48:07 INFO - 'buildbot_json_path': 'buildprops.json', 18:48:07 INFO - 'code_coverage': False, 18:48:07 INFO - 'config_files': ('talos/linux_config.py',), 18:48:07 INFO - 'default_actions': ('clobber', 18:48:07 INFO - 'read-buildbot-config', 18:48:07 INFO - 'download-and-extract', 18:48:07 INFO - 'populate-webroot', 18:48:07 INFO - 'create-virtualenv', 18:48:07 INFO - 'install', 18:48:07 INFO - 'setup-mitmproxy', 18:48:07 INFO - 'run-tests'), 18:48:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:48:07 INFO - 'disable_ccov_upload': False, 18:48:07 INFO - 'disable_stylo': False, 18:48:07 INFO - 'download_minidump_stackwalk': True, 18:48:07 INFO - 'download_symbols': 'ondemand', 18:48:07 INFO - 'enable_stylo': False, 18:48:07 INFO - 'enable_webrender': False, 18:48:07 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:48:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:48:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:48:07 INFO - 'gecko_profile': False, 18:48:07 INFO - 'gecko_profile_interval': 0, 18:48:07 INFO - 'installer_path': 'installer.exe', 18:48:07 INFO - 'log_level': 'info', 18:48:07 INFO - 'log_name': 'talos', 18:48:07 INFO - 'log_to_console': True, 18:48:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:48:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:48:07 INFO - 'opt_config_files': (), 18:48:07 INFO - 'pip_index': False, 18:48:07 INFO - 'suite': 'dromaeojs-stylo-disabled-e10s', 18:48:07 INFO - 'system_bits': '32', 18:48:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:48:07 INFO - 'title': 'talos-linux64-ix-027', 18:48:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:48:07 INFO - 'use_talos_json': True, 18:48:07 INFO - 'verify': 'False', 18:48:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:48:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:48:07 INFO - 'work_dir': 'build'} 18:48:07 INFO - [mozharness: 2017-09-21 01:48:07.251853Z] Running clobber step. 18:48:07 INFO - Running pre-action listener: _resource_record_pre_action 18:48:07 INFO - Running main action method: clobber 18:48:07 INFO - rmtree: /builds/slave/test/build 18:48:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:48:08 INFO - Running post-action listener: _resource_record_post_action 18:48:08 INFO - [mozharness: 2017-09-21 01:48:08.243110Z] Finished clobber step (success) 18:48:08 INFO - [mozharness: 2017-09-21 01:48:08.243207Z] Running read-buildbot-config step. 18:48:08 INFO - Running pre-action listener: _resource_record_pre_action 18:48:08 INFO - Running main action method: read_buildbot_config 18:48:08 INFO - Using buildbot properties: 18:48:08 INFO - { 18:48:08 INFO - "project": "", 18:48:08 INFO - "product": "firefox", 18:48:08 INFO - "who": "kwierso@gmail.com", 18:48:08 INFO - "installer_path": "public/build/target.tar.bz2", 18:48:08 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-stylo-disabled-e10s", 18:48:08 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:48:08 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-stylo-disabled-e10s", 18:48:08 INFO - "stage_platform": "linux64-pgo", 18:48:08 INFO - "basedir": "/builds/slave/test", 18:48:08 INFO - "buildnumber": 43, 18:48:08 INFO - "platform": "ubuntu64_hw", 18:48:08 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:48:08 INFO - "slavebuilddir": "test", 18:48:08 INFO - "taskId": "b5RQQ4uaTOeI_t0HPFBGbg", 18:48:08 INFO - "branch": "mozilla-central", 18:48:08 INFO - "script_repo_revision": "production", 18:48:08 INFO - "revision": "f8dd3f21e434be32fe5901849f1723b0e64cf668", 18:48:08 INFO - "slavename": "talos-linux64-ix-027", 18:48:08 INFO - "repo_path": "mozilla-central" 18:48:08 INFO - } 18:48:08 INFO - Finding installer, test and symbols from parent task. 18:48:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b5RQQ4uaTOeI_t0HPFBGbg'}, attempt #1 18:48:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b5RQQ4uaTOeI_t0HPFBGbg'}, attempt #1 18:48:09 INFO - Task dependencies: BqbOEH4RRjOmGYuwp-1rsQ 18:48:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ'}, attempt #1 18:48:09 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.tar.bz2 18:48:09 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.test_packages.json 18:48:09 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.crashreporter-symbols.zip 18:48:09 INFO - Running post-action listener: _resource_record_post_action 18:48:09 INFO - [mozharness: 2017-09-21 01:48:09.676865Z] Finished read-buildbot-config step (success) 18:48:09 INFO - [mozharness: 2017-09-21 01:48:09.677082Z] Running download-and-extract step. 18:48:09 INFO - Running pre-action listener: _resource_record_pre_action 18:48:09 INFO - Running main action method: download_and_extract 18:48:09 INFO - mkdir: /builds/slave/test/build/tests 18:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:09 INFO - trying https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.test_packages.json 18:48:09 INFO - Downloading https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:48:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:48:11 INFO - Downloaded 989 bytes. 18:48:11 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:48:11 INFO - Using the following test package requirements: 18:48:11 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:48:11 INFO - u'common': [u'target.common.tests.zip'], 18:48:11 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:48:11 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:48:11 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:48:11 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:48:11 INFO - u'mozbase': [u'target.common.tests.zip'], 18:48:11 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:48:11 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:48:11 INFO - u'web-platform': [u'target.common.tests.zip', 18:48:11 INFO - u'target.web-platform.tests.tar.gz'], 18:48:11 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:48:11 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 18:48:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.common.tests.zip 18:48:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:48:11 INFO - Fetch https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.common.tests.zip into memory 18:48:12 INFO - Content-Length response header: 55285111 18:48:12 INFO - Bytes received: 55285111 18:48:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.talos.tests.zip 18:48:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:48:23 INFO - Fetch https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.talos.tests.zip into memory 18:48:23 INFO - Content-Length response header: 14512509 18:48:23 INFO - Bytes received: 14512509 18:48:24 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')]} 18:48:24 INFO - trying https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.tar.bz2 18:48:24 INFO - Downloading https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:48:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:48:25 INFO - Downloaded 62235041 bytes. 18:48:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.tar.bz2 18:48:25 INFO - mkdir: /builds/slave/test/properties 18:48:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:48:25 INFO - Writing to file /builds/slave/test/properties/build_url 18:48:25 INFO - Contents: 18:48:25 INFO - build_url:https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.tar.bz2 18:48:25 INFO - Running post-action listener: _resource_record_post_action 18:48:25 INFO - Running post-action listener: find_tests_for_verification 18:48:25 INFO - Running post-action listener: set_extra_try_arguments 18:48:25 INFO - [mozharness: 2017-09-21 01:48:25.078862Z] Finished download-and-extract step (success) 18:48:25 INFO - [mozharness: 2017-09-21 01:48:25.078959Z] Running populate-webroot step. 18:48:25 INFO - Running pre-action listener: _resource_record_pre_action 18:48:25 INFO - Running main action method: populate_webroot 18:48:25 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:48:25 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:48:25 INFO - 'tests': ['tresize', 18:48:25 INFO - 'tcanvasmark']}, 18:48:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:48:25 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:48:25 INFO - 'tests': ['dromaeo_css', 18:48:25 INFO - 'kraken']}, 18:48:25 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:48:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:48:25 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:48:25 INFO - 'talos_options': ['--disable-stylo'], 18:48:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:48:25 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:48:25 INFO - 'tests': ['damp', 'tps']}, 18:48:25 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:48:25 INFO - 'talos_options': ['--disable-stylo'], 18:48:25 INFO - 'tests': ['damp', 'tps']}, 18:48:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:48:25 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:48:25 INFO - 'tests': ['dromaeo_dom']}, 18:48:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:48:25 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:48:25 INFO - 'tests': ['basic_compositor_video', 18:48:25 INFO - 'glvideo']}, 18:48:25 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:48:25 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:48:25 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:48:25 INFO - 'talos_options': ['--disable-stylo'], 18:48:25 INFO - 'tests': ['ts_paint_webext', 18:48:25 INFO - 'tp5o_webext']}, 18:48:25 INFO - 'other-e10s': {'tests': ['a11yr', 18:48:25 INFO - 'ts_paint', 18:48:25 INFO - 'tpaint', 18:48:25 INFO - 'sessionrestore', 18:48:25 INFO - 'sessionrestore_many_windows', 18:48:25 INFO - 'sessionrestore_no_auto_restore', 18:48:25 INFO - 'tabpaint']}, 18:48:25 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:48:25 INFO - 'tests': ['a11yr', 18:48:25 INFO - 'ts_paint', 18:48:25 INFO - 'tpaint', 18:48:25 INFO - 'sessionrestore', 18:48:25 INFO - 'sessionrestore_many_windows', 18:48:25 INFO - 'sessionrestore_no_auto_restore', 18:48:25 INFO - 'tabpaint']}, 18:48:25 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 18:48:25 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 18:48:25 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:48:25 INFO - 'tests': ['perf_reftest_singletons']}, 18:48:25 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:48:25 INFO - 'tests': ['bloom_basic']}, 18:48:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:48:25 INFO - 'tsvgr_opacity', 18:48:25 INFO - 'tart', 18:48:25 INFO - 'tscrollx', 18:48:25 INFO - 'tsvg_static']}, 18:48:25 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:48:25 INFO - 'tests': ['tsvgx', 18:48:25 INFO - 'tsvgr_opacity', 18:48:25 INFO - 'tart', 18:48:25 INFO - 'tscrollx', 18:48:25 INFO - 'tsvg_static']}, 18:48:25 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:48:25 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:48:25 INFO - 'talos_options': ['--disable-stylo'], 18:48:25 INFO - 'tests': ['tp5o']}, 18:48:25 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:48:25 INFO - 'talos_options': ['--mitmproxy', 18:48:25 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:48:25 INFO - '--firstNonBlankPaint'], 18:48:25 INFO - 'tests': ['tp6_google', 18:48:25 INFO - 'tp6_youtube', 18:48:25 INFO - 'tp6_amazon', 18:48:25 INFO - 'tp6_facebook']}, 18:48:25 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:48:25 INFO - 'talos_options': ['--disable-stylo', 18:48:25 INFO - '--mitmproxy', 18:48:25 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:48:25 INFO - '--firstNonBlankPaint'], 18:48:25 INFO - 'tests': ['tp6_google', 18:48:25 INFO - 'tp6_youtube', 18:48:25 INFO - 'tp6_amazon', 18:48:25 INFO - 'tp6_facebook']}, 18:48:25 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:48:25 INFO - 'talos_options': ['--stylo-threads=1', 18:48:25 INFO - '--mitmproxy', 18:48:25 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:48:25 INFO - '--firstNonBlankPaint'], 18:48:25 INFO - 'tests': ['tp6_google', 18:48:25 INFO - 'tp6_youtube', 18:48:25 INFO - 'tp6_amazon', 18:48:25 INFO - 'tp6_facebook']}, 18:48:25 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:48:25 INFO - 'talos_options': ['--xperf_path', 18:48:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:48:25 INFO - 'tests': ['tp5n']}, 18:48:25 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:48:25 INFO - 'talos_options': ['--disable-stylo', 18:48:25 INFO - '--xperf_path', 18:48:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:48:25 INFO - 'tests': ['tp5n']}}} 18:48:25 INFO - Running post-action listener: _resource_record_post_action 18:48:25 INFO - [mozharness: 2017-09-21 01:48:25.093857Z] Finished populate-webroot step (success) 18:48:25 INFO - [mozharness: 2017-09-21 01:48:25.093946Z] Running create-virtualenv step. 18:48:25 INFO - Running pre-action listener: _resource_record_pre_action 18:48:25 INFO - Running main action method: create_virtualenv 18:48:25 INFO - Creating virtualenv /builds/slave/test/build/venv 18:48:25 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:48:25 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:48:25 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:48:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:48:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:48:25 INFO - Installing distribute.............................................................................................................................................................................................done. 18:48:25 INFO - Installing pip...............done. 18:48:25 INFO - Return code: 0 18:48:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:48:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:48:26 INFO - Reading from file tmpfile_stdout 18:48:26 INFO - Output received: 18:48:26 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:48:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:48:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:26 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 0xcf91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1065a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069090>, '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 0x1069d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063f90>, '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-1505958484.852817-104608235', '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 18:48:26 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 18:48:26 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 18:48:26 INFO - Using env: {'DISPLAY': ':0', 18:48:26 INFO - 'HOME': '/home/cltbld', 18:48:26 INFO - 'LANG': 'en_US.UTF-8', 18:48:26 INFO - 'LANGUAGE': 'en_US:en', 18:48:26 INFO - 'LOGNAME': 'cltbld', 18:48:26 INFO - 'MAIL': '/var/mail/cltbld', 18:48:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:26 INFO - 'MOZ_NO_REMOTE': '1', 18:48:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:26 INFO - 'NO_EM_RESTART': '1', 18:48:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:26 INFO - 'PWD': '/builds/slave/test', 18:48:26 INFO - 'SHELL': '/bin/bash', 18:48:26 INFO - 'SHLVL': '1', 18:48:26 INFO - 'TERM': 'linux', 18:48:26 INFO - 'TMOUT': '86400', 18:48:26 INFO - 'USER': 'cltbld', 18:48:26 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1505958484.852817-104608235', 18:48:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:26 INFO - '_': '/tools/buildbot/bin/python'} 18:48:27 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:48:27 INFO - Downloading/unpacking pip>=1.5 18:48:27 INFO - Running setup.py egg_info for package pip 18:48:27 INFO - warning: no files found matching 'pip/cacert.pem' 18:48:27 INFO - warning: no files found matching '*.html' under directory 'docs' 18:48:27 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:48:27 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:48:27 INFO - Installing collected packages: pip 18:48:27 INFO - Found existing installation: pip 1.1 18:48:27 INFO - Uninstalling pip: 18:48:27 INFO - Successfully uninstalled pip 18:48:27 INFO - Running setup.py install for pip 18:48:27 INFO - warning: no files found matching 'pip/cacert.pem' 18:48:27 INFO - warning: no files found matching '*.html' under directory 'docs' 18:48:27 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:48:27 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:48:27 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:48:27 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:48:27 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:48:27 INFO - Successfully installed pip 18:48:27 INFO - Cleaning up... 18:48:27 INFO - Return code: 0 18:48:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:48:27 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')]} 18:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:27 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')]} 18:48:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:27 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 0xcf91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1065a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069090>, '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 0x1069d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063f90>, '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-1505958484.852817-104608235', '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 18:48:27 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 18:48:27 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 18:48:27 INFO - Using env: {'DISPLAY': ':0', 18:48:27 INFO - 'HOME': '/home/cltbld', 18:48:27 INFO - 'LANG': 'en_US.UTF-8', 18:48:27 INFO - 'LANGUAGE': 'en_US:en', 18:48:27 INFO - 'LOGNAME': 'cltbld', 18:48:27 INFO - 'MAIL': '/var/mail/cltbld', 18:48:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:27 INFO - 'MOZ_NO_REMOTE': '1', 18:48:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:27 INFO - 'NO_EM_RESTART': '1', 18:48:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:27 INFO - 'PWD': '/builds/slave/test', 18:48:27 INFO - 'SHELL': '/bin/bash', 18:48:27 INFO - 'SHLVL': '1', 18:48:27 INFO - 'TERM': 'linux', 18:48:27 INFO - 'TMOUT': '86400', 18:48:27 INFO - 'USER': 'cltbld', 18:48:27 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1505958484.852817-104608235', 18:48:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:27 INFO - '_': '/tools/buildbot/bin/python'} 18:48:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:27 INFO - Downloading/unpacking psutil>=3.1.1 18:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:48:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:48:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:48:28 INFO - Installing collected packages: psutil 18:48:28 INFO - Running setup.py install for psutil 18:48:28 INFO - building 'psutil._psutil_linux' extension 18:48:28 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 18:48:28 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 18:48:29 INFO - building 'psutil._psutil_posix' extension 18:48:29 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 18:48:29 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 18:48:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:48:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:48:29 INFO - Successfully installed psutil 18:48:29 INFO - Cleaning up... 18:48:29 INFO - Return code: 0 18:48:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:48:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:29 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 0xcf91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1065a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069090>, '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 0x1069d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063f90>, '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-1505958484.852817-104608235', '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 18:48:29 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 18:48:29 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 18:48:29 INFO - Using env: {'DISPLAY': ':0', 18:48:29 INFO - 'HOME': '/home/cltbld', 18:48:29 INFO - 'LANG': 'en_US.UTF-8', 18:48:29 INFO - 'LANGUAGE': 'en_US:en', 18:48:29 INFO - 'LOGNAME': 'cltbld', 18:48:29 INFO - 'MAIL': '/var/mail/cltbld', 18:48:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:29 INFO - 'MOZ_NO_REMOTE': '1', 18:48:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:29 INFO - 'NO_EM_RESTART': '1', 18:48:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:29 INFO - 'PWD': '/builds/slave/test', 18:48:29 INFO - 'SHELL': '/bin/bash', 18:48:29 INFO - 'SHLVL': '1', 18:48:29 INFO - 'TERM': 'linux', 18:48:29 INFO - 'TMOUT': '86400', 18:48:29 INFO - 'USER': 'cltbld', 18:48:29 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1505958484.852817-104608235', 18:48:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:29 INFO - '_': '/tools/buildbot/bin/python'} 18:48:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:48:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:48:30 INFO - Installing collected packages: mozsystemmonitor 18:48:30 INFO - Running setup.py install for mozsystemmonitor 18:48:30 INFO - Successfully installed mozsystemmonitor 18:48:30 INFO - Cleaning up... 18:48:31 INFO - Return code: 0 18:48:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:48: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')]} 18:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48: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')]} 18:48:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcf91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1065a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069090>, '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 0x1069d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063f90>, '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-1505958484.852817-104608235', '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 18:48:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:48:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:48:31 INFO - Using env: {'DISPLAY': ':0', 18:48:31 INFO - 'HOME': '/home/cltbld', 18:48:31 INFO - 'LANG': 'en_US.UTF-8', 18:48:31 INFO - 'LANGUAGE': 'en_US:en', 18:48:31 INFO - 'LOGNAME': 'cltbld', 18:48:31 INFO - 'MAIL': '/var/mail/cltbld', 18:48:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:31 INFO - 'MOZ_NO_REMOTE': '1', 18:48:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:31 INFO - 'NO_EM_RESTART': '1', 18:48:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:31 INFO - 'PWD': '/builds/slave/test', 18:48:31 INFO - 'SHELL': '/bin/bash', 18:48:31 INFO - 'SHLVL': '1', 18:48:31 INFO - 'TERM': 'linux', 18:48:31 INFO - 'TMOUT': '86400', 18:48:31 INFO - 'USER': 'cltbld', 18:48:31 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1505958484.852817-104608235', 18:48:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:31 INFO - '_': '/tools/buildbot/bin/python'} 18:48:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:31 INFO - Downloading/unpacking jsonschema==2.5.1 18:48:31 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 18:48:31 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 18:48:31 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 18:48:31 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 18:48:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:48:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:48:32 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 18:48:32 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 18:48:32 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 18:48:32 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 18:48:32 INFO - Downloading functools32-3.2.3-2.tar.gz 18:48:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:48:33 INFO - warning: no files found matching '*.txt' 18:48:33 INFO - no previously-included directories found matching 'build' 18:48:33 INFO - no previously-included directories found matching 'dist' 18:48:33 INFO - no previously-included directories found matching '.git*' 18:48:33 INFO - Installing collected packages: jsonschema, functools32 18:48:33 INFO - Running setup.py install for functools32 18:48:33 INFO - warning: no files found matching '*.txt' 18:48:33 INFO - no previously-included directories found matching 'build' 18:48:33 INFO - no previously-included directories found matching 'dist' 18:48:33 INFO - no previously-included directories found matching '.git*' 18:48:33 INFO - Successfully installed jsonschema functools32 18:48:33 INFO - Cleaning up... 18:48:34 INFO - Return code: 0 18:48:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:48: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')]} 18:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48: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')]} 18:48:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48: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', '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 0xcf91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1065a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069090>, '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 0x1069d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063f90>, '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-1505958484.852817-104608235', '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 18:48: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:48: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 functools32==3.2.3-2 18:48:34 INFO - Using env: {'DISPLAY': ':0', 18:48:34 INFO - 'HOME': '/home/cltbld', 18:48:34 INFO - 'LANG': 'en_US.UTF-8', 18:48:34 INFO - 'LANGUAGE': 'en_US:en', 18:48:34 INFO - 'LOGNAME': 'cltbld', 18:48:34 INFO - 'MAIL': '/var/mail/cltbld', 18:48:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:34 INFO - 'MOZ_NO_REMOTE': '1', 18:48:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:34 INFO - 'NO_EM_RESTART': '1', 18:48:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:34 INFO - 'PWD': '/builds/slave/test', 18:48:34 INFO - 'SHELL': '/bin/bash', 18:48:34 INFO - 'SHLVL': '1', 18:48:34 INFO - 'TERM': 'linux', 18:48:34 INFO - 'TMOUT': '86400', 18:48:34 INFO - 'USER': 'cltbld', 18:48:34 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1505958484.852817-104608235', 18:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:34 INFO - '_': '/tools/buildbot/bin/python'} 18:48:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:48:34 INFO - Cleaning up... 18:48:34 INFO - Return code: 0 18:48:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:48: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')]} 18:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48: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')]} 18:48:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48: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', '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 0xcf91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1065a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069090>, '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 0x1069d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063f90>, '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-1505958484.852817-104608235', '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 18:48: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:48: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 blobuploader==1.2.4 18:48:34 INFO - Using env: {'DISPLAY': ':0', 18:48:34 INFO - 'HOME': '/home/cltbld', 18:48:34 INFO - 'LANG': 'en_US.UTF-8', 18:48:34 INFO - 'LANGUAGE': 'en_US:en', 18:48:34 INFO - 'LOGNAME': 'cltbld', 18:48:34 INFO - 'MAIL': '/var/mail/cltbld', 18:48:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:34 INFO - 'MOZ_NO_REMOTE': '1', 18:48:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:34 INFO - 'NO_EM_RESTART': '1', 18:48:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:34 INFO - 'PWD': '/builds/slave/test', 18:48:34 INFO - 'SHELL': '/bin/bash', 18:48:34 INFO - 'SHLVL': '1', 18:48:34 INFO - 'TERM': 'linux', 18:48:34 INFO - 'TMOUT': '86400', 18:48:34 INFO - 'USER': 'cltbld', 18:48:34 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1505958484.852817-104608235', 18:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:34 INFO - '_': '/tools/buildbot/bin/python'} 18:48:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:34 INFO - Downloading/unpacking blobuploader==1.2.4 18:48:34 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 18:48:34 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 18:48:34 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 18:48:34 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 18:48:35 INFO - Downloading blobuploader-1.2.4.tar.gz 18:48:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:48:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:48:35 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 18:48:35 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 18:48:35 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 18:48:35 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 18:48:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:48:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:48:36 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 18:48:36 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 18:48:36 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 18:48:36 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 18:48:36 INFO - Downloading docopt-0.6.1.tar.gz 18:48:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:48:36 INFO - Installing collected packages: blobuploader, requests, docopt 18:48:36 INFO - Running setup.py install for blobuploader 18:48:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:48:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:48:36 INFO - Running setup.py install for requests 18:48:36 INFO - Running setup.py install for docopt 18:48:36 INFO - Successfully installed blobuploader requests docopt 18:48:36 INFO - Cleaning up... 18:48:36 INFO - Return code: 0 18:48:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:36 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 0xcf91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1065a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069090>, '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 0x1069d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063f90>, '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-1505958484.852817-104608235', '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 18:48:36 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 18:48:36 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 18:48:36 INFO - Using env: {'DISPLAY': ':0', 18:48:36 INFO - 'HOME': '/home/cltbld', 18:48:36 INFO - 'LANG': 'en_US.UTF-8', 18:48:36 INFO - 'LANGUAGE': 'en_US:en', 18:48:36 INFO - 'LOGNAME': 'cltbld', 18:48:36 INFO - 'MAIL': '/var/mail/cltbld', 18:48:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:36 INFO - 'MOZ_NO_REMOTE': '1', 18:48:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:36 INFO - 'NO_EM_RESTART': '1', 18:48:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:36 INFO - 'PWD': '/builds/slave/test', 18:48:36 INFO - 'SHELL': '/bin/bash', 18:48:36 INFO - 'SHLVL': '1', 18:48:36 INFO - 'TERM': 'linux', 18:48:36 INFO - 'TMOUT': '86400', 18:48:36 INFO - 'USER': 'cltbld', 18:48:36 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1505958484.852817-104608235', 18:48:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:36 INFO - '_': '/tools/buildbot/bin/python'} 18:48:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:48:37 INFO - Running setup.py (path:/tmp/pip-4OzN4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:48:37 INFO - Running setup.py (path:/tmp/pip-GgfhCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:48:37 INFO - Running setup.py (path:/tmp/pip-Tdxuw9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:48:37 INFO - Running setup.py (path:/tmp/pip-i_TdyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:48:37 INFO - Running setup.py (path:/tmp/pip-Dx8AzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:48:37 INFO - Running setup.py (path:/tmp/pip-ErDUd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:48:37 INFO - Running setup.py (path:/tmp/pip-zgcmi_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:48:37 INFO - Running setup.py (path:/tmp/pip-OLFoLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:48:37 INFO - Running setup.py (path:/tmp/pip-sqBgHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:48:37 INFO - Running setup.py (path:/tmp/pip-tUy9Bw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:48:37 INFO - Running setup.py (path:/tmp/pip-RAYRZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:48:37 INFO - Running setup.py (path:/tmp/pip-vYsWkZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:48:37 INFO - Running setup.py (path:/tmp/pip-9GqEDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:48:37 INFO - Running setup.py (path:/tmp/pip-KWwSYC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:48:38 INFO - Running setup.py (path:/tmp/pip-LwimUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:48:38 INFO - Running setup.py (path:/tmp/pip-HU3dbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:48:38 INFO - Running setup.py (path:/tmp/pip-13H9mK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:48:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:48:38 INFO - Running setup.py install for manifestparser 18:48:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:48:38 INFO - Running setup.py install for mozcrash 18:48:38 INFO - Running setup.py install for mozdebug 18:48:38 INFO - Running setup.py install for mozdevice 18:48:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:48:38 INFO - Running setup.py install for mozfile 18:48:38 INFO - Running setup.py install for mozhttpd 18:48:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:48:38 INFO - Running setup.py install for mozinfo 18:48:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:48:38 INFO - Running setup.py install for mozInstall 18:48:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:48:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:48:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:48:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:48:39 INFO - Running setup.py install for mozleak 18:48:39 INFO - Running setup.py install for mozlog 18:48:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:48:39 INFO - Running setup.py install for moznetwork 18:48:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:48:39 INFO - Running setup.py install for mozprocess 18:48:39 INFO - Running setup.py install for mozprofile 18:48:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:48:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:48:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:48:39 INFO - Running setup.py install for mozrunner 18:48:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:48:39 INFO - Running setup.py install for mozscreenshot 18:48:39 INFO - Running setup.py install for moztest 18:48:39 INFO - Running setup.py install for mozversion 18:48:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:48:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:48:39 INFO - Cleaning up... 18:48:39 INFO - Return code: 0 18:48:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:48: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')]} 18:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48: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')]} 18:48:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:40 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 0xcf91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1065a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069090>, '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 0x1069d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063f90>, '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-1505958484.852817-104608235', '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 18:48:40 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 18:48:40 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 18:48:40 INFO - Using env: {'DISPLAY': ':0', 18:48:40 INFO - 'HOME': '/home/cltbld', 18:48:40 INFO - 'LANG': 'en_US.UTF-8', 18:48:40 INFO - 'LANGUAGE': 'en_US:en', 18:48:40 INFO - 'LOGNAME': 'cltbld', 18:48:40 INFO - 'MAIL': '/var/mail/cltbld', 18:48:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:40 INFO - 'MOZ_NO_REMOTE': '1', 18:48:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:40 INFO - 'NO_EM_RESTART': '1', 18:48:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:40 INFO - 'PWD': '/builds/slave/test', 18:48:40 INFO - 'SHELL': '/bin/bash', 18:48:40 INFO - 'SHLVL': '1', 18:48:40 INFO - 'TERM': 'linux', 18:48:40 INFO - 'TMOUT': '86400', 18:48:40 INFO - 'USER': 'cltbld', 18:48:40 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1505958484.852817-104608235', 18:48:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:40 INFO - '_': '/tools/buildbot/bin/python'} 18:48:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:48:40 INFO - Running setup.py (path:/tmp/pip-1qW5x7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:48:40 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)) 18:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:48:40 INFO - Running setup.py (path:/tmp/pip-HARJe3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:48:40 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)) 18:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:48:40 INFO - Running setup.py (path:/tmp/pip-ixikEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:48:40 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)) 18:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:48:40 INFO - Running setup.py (path:/tmp/pip-qBcDzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:48:40 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)) 18:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:48:40 INFO - Running setup.py (path:/tmp/pip-YjIQGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:48:40 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)) 18:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:48:40 INFO - Running setup.py (path:/tmp/pip-BqaW6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:48:40 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)) 18:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:48:40 INFO - Running setup.py (path:/tmp/pip-jPiMIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:48:40 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)) 18:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:48:40 INFO - Running setup.py (path:/tmp/pip-pu3JRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:48:40 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)) 18:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:48:40 INFO - Running setup.py (path:/tmp/pip-QZMnvV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:48:40 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)) 18:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:48:40 INFO - Running setup.py (path:/tmp/pip-AW8tLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:48:40 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)) 18:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:48:40 INFO - Running setup.py (path:/tmp/pip-h41Gcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:48:40 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)) 18:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:48:40 INFO - Running setup.py (path:/tmp/pip-Qml4Np-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:48:41 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)) 18:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:48:41 INFO - Running setup.py (path:/tmp/pip-o6SJkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:48:41 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)) 18:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:48:41 INFO - Running setup.py (path:/tmp/pip-nbT7zl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:48:41 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)) 18:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:48:41 INFO - Running setup.py (path:/tmp/pip-DyTXxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:48:41 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)) 18:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:48:41 INFO - Running setup.py (path:/tmp/pip-YTE_MN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:48:41 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)) 18:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:48:41 INFO - Running setup.py (path:/tmp/pip-hcWZda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:48:41 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)) 18:48:41 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)) 18:48:41 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)) 18:48:41 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)) 18:48:41 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)) 18:48:41 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)) 18:48:41 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)) 18:48:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:42 INFO - Downloading blessings-1.6.tar.gz 18:48:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:48:42 INFO - Installing collected packages: blessings 18:48:42 INFO - Running setup.py install for blessings 18:48:42 INFO - Successfully installed blessings 18:48:42 INFO - Cleaning up... 18:48:42 INFO - Return code: 0 18:48:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:48:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:48:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:48:42 INFO - Reading from file tmpfile_stdout 18:48:42 INFO - Current package versions: 18:48:42 INFO - argparse == 1.2.1 18:48:42 INFO - blessings == 1.6 18:48:42 INFO - blobuploader == 1.2.4 18:48:42 INFO - docopt == 0.6.1 18:48:42 INFO - functools32 == 3.2.3-2 18:48:42 INFO - jsonschema == 2.5.1 18:48:42 INFO - manifestparser == 1.1 18:48:42 INFO - mozInstall == 1.13 18:48:42 INFO - mozcrash == 1.0 18:48:42 INFO - mozdebug == 0.1 18:48:42 INFO - mozdevice == 0.51 18:48:42 INFO - mozfile == 1.2 18:48:42 INFO - mozhttpd == 0.7 18:48:42 INFO - mozinfo == 0.10 18:48:42 INFO - mozleak == 0.1 18:48:42 INFO - mozlog == 3.5 18:48:42 INFO - moznetwork == 0.27 18:48:42 INFO - mozprocess == 0.25 18:48:42 INFO - mozprofile == 0.28 18:48:42 INFO - mozrunner == 6.13 18:48:42 INFO - mozscreenshot == 0.1 18:48:42 INFO - mozsystemmonitor == 0.3 18:48:42 INFO - moztest == 0.8 18:48:42 INFO - mozversion == 1.4 18:48:42 INFO - psutil == 3.1.1 18:48:42 INFO - requests == 1.2.3 18:48:42 INFO - wsgiref == 0.1.2 18:48:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:42 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 0xcf91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1065a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069090>, '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 0x1069d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063f90>, '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-1505958484.852817-104608235', '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 18:48:42 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 18:48:42 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 18:48:43 INFO - Using env: {'DISPLAY': ':0', 18:48:43 INFO - 'HOME': '/home/cltbld', 18:48:43 INFO - 'LANG': 'en_US.UTF-8', 18:48:43 INFO - 'LANGUAGE': 'en_US:en', 18:48:43 INFO - 'LOGNAME': 'cltbld', 18:48:43 INFO - 'MAIL': '/var/mail/cltbld', 18:48:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:43 INFO - 'MOZ_NO_REMOTE': '1', 18:48:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:43 INFO - 'NO_EM_RESTART': '1', 18:48:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:43 INFO - 'PWD': '/builds/slave/test', 18:48:43 INFO - 'SHELL': '/bin/bash', 18:48:43 INFO - 'SHLVL': '1', 18:48:43 INFO - 'TERM': 'linux', 18:48:43 INFO - 'TMOUT': '86400', 18:48:43 INFO - 'USER': 'cltbld', 18:48:43 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1505958484.852817-104608235', 18:48:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:43 INFO - '_': '/tools/buildbot/bin/python'} 18:48:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:43 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)) 18:48:43 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)) 18:48:43 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)) 18:48:43 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)) 18:48:43 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)) 18:48:43 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)) 18:48:43 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)) 18:48:43 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)) 18:48:43 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)) 18:48:43 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:48:44 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)) 18:48:44 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)) 18:48:44 INFO - Installing collected packages: simplejson 18:48:44 INFO - Running setup.py install for simplejson 18:48:44 INFO - building 'simplejson._speedups' extension 18:48:44 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 18:48:45 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 18:48:45 INFO - Successfully installed simplejson 18:48:45 INFO - Cleaning up... 18:48:45 INFO - Return code: 0 18:48:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:48:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:45 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 0xcf91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1065a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069090>, '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 0x1069d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063f90>, '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-1505958484.852817-104608235', '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 18:48:45 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 18:48:45 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 18:48:45 INFO - Using env: {'DISPLAY': ':0', 18:48:45 INFO - 'HOME': '/home/cltbld', 18:48:45 INFO - 'LANG': 'en_US.UTF-8', 18:48:45 INFO - 'LANGUAGE': 'en_US:en', 18:48:45 INFO - 'LOGNAME': 'cltbld', 18:48:45 INFO - 'MAIL': '/var/mail/cltbld', 18:48:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:45 INFO - 'MOZ_NO_REMOTE': '1', 18:48:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:45 INFO - 'NO_EM_RESTART': '1', 18:48:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:45 INFO - 'PWD': '/builds/slave/test', 18:48:45 INFO - 'SHELL': '/bin/bash', 18:48:45 INFO - 'SHLVL': '1', 18:48:45 INFO - 'TERM': 'linux', 18:48:45 INFO - 'TMOUT': '86400', 18:48:45 INFO - 'USER': 'cltbld', 18:48:45 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1505958484.852817-104608235', 18:48:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:45 INFO - '_': '/tools/buildbot/bin/python'} 18:48:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:48:45 INFO - Cleaning up... 18:48:45 INFO - Return code: 0 18:48:45 INFO - Running post-action listener: _resource_record_post_action 18:48:45 INFO - Running post-action listener: _start_resource_monitoring 18:48:45 INFO - Starting resource monitoring. 18:48:45 INFO - [mozharness: 2017-09-21 01:48:45.696642Z] Finished create-virtualenv step (success) 18:48:45 INFO - [mozharness: 2017-09-21 01:48:45.697155Z] Running install step. 18:48:45 INFO - Running pre-action listener: _resource_record_pre_action 18:48:45 INFO - Running main action method: install 18:48:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:48:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:48:45 INFO - Reading from file tmpfile_stdout 18:48:45 INFO - Detecting whether we're running mozinstall >=1.0... 18:48:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:48:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:48:46 INFO - Reading from file tmpfile_stdout 18:48:46 INFO - Output received: 18:48:46 INFO - Usage: mozinstall [options] installer 18:48:46 INFO - Options: 18:48:46 INFO - -h, --help show this help message and exit 18:48:46 INFO - -d DEST, --destination=DEST 18:48:46 INFO - Directory to install application into. [default: 18:48:46 INFO - "/builds/slave/test"] 18:48:46 INFO - --app=APP Application being installed. [default: firefox] 18:48:46 INFO - mkdir: /builds/slave/test/build/application 18:48:46 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'] 18:48:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:49:03 INFO - Reading from file tmpfile_stdout 18:49:03 INFO - Output received: 18:49:03 INFO - /builds/slave/test/build/application/firefox/firefox 18:49:03 INFO - Running post-action listener: _resource_record_post_action 18:49:03 INFO - [mozharness: 2017-09-21 01:49:03.800675Z] Finished install step (success) 18:49:03 INFO - [mozharness: 2017-09-21 01:49:03.800822Z] Running setup-mitmproxy step. 18:49:03 INFO - Running pre-action listener: _resource_record_pre_action 18:49:03 INFO - Running main action method: setup_mitmproxy 18:49:03 INFO - Skipping: mitmproxy is not required 18:49:03 INFO - Running post-action listener: _resource_record_post_action 18:49:03 INFO - [mozharness: 2017-09-21 01:49:03.801358Z] Finished setup-mitmproxy step (success) 18:49:03 INFO - [mozharness: 2017-09-21 01:49:03.801576Z] Running run-tests step. 18:49:03 INFO - Running pre-action listener: _resource_record_pre_action 18:49:03 INFO - Running pre-action listener: _set_gcov_prefix 18:49:03 INFO - Running main action method: run_tests 18:49:03 WARNING - Try message not found. 18:49:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:49:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:49:03 INFO - Python 2.7.3 18:49:03 INFO - Return code: 0 18:49:03 INFO - grabbing minidump binary from tooltool 18:49:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:03 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 0x1069090>, '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 0x1069d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:49:03 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 18:49:03 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 18:49:03 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 18:49:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:49:03 INFO - Return code: 0 18:49:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:49:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:49:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:49:03 INFO - ENV: RUST_BACKTRACE is now full 18:49:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:49:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:49:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/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-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log'] in /builds/slave/test/build 18:49:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-stylo-disabled-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/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-stylo-disabled-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log 18:49:03 INFO - Using env: {'DISPLAY': ':0', 18:49:03 INFO - 'HOME': '/home/cltbld', 18:49:03 INFO - 'LANG': 'en_US.UTF-8', 18:49:03 INFO - 'LANGUAGE': 'en_US:en', 18:49:03 INFO - 'LOGNAME': 'cltbld', 18:49:03 INFO - 'MAIL': '/var/mail/cltbld', 18:49:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:49:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:49:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:03 INFO - 'MOZ_NO_REMOTE': '1', 18:49:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:49:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:03 INFO - 'NO_EM_RESTART': '1', 18:49:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:03 INFO - 'PWD': '/builds/slave/test', 18:49:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:49:03 INFO - 'RUST_BACKTRACE': 'full', 18:49:03 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:49:03 INFO - 'SHELL': '/bin/bash', 18:49:03 INFO - 'SHLVL': '1', 18:49:03 INFO - 'TERM': 'linux', 18:49:03 INFO - 'TMOUT': '86400', 18:49:03 INFO - 'USER': 'cltbld', 18:49:03 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1505958484.852817-104608235', 18:49:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:03 INFO - '_': '/tools/buildbot/bin/python'} 18:49:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/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-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log'] with output_timeout 3600 18:49:04 INFO - mozversion application_buildid: 20170921003054 18:49:04 INFO - mozversion application_changeset: f8dd3f21e434be32fe5901849f1723b0e64cf668 18:49:04 INFO - mozversion application_display_name: Nightly 18:49:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:49:04 INFO - mozversion application_name: Firefox 18:49:04 INFO - mozversion application_remotingname: firefox 18:49:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:49:04 INFO - mozversion application_vendor: Mozilla 18:49:04 INFO - mozversion application_version: 57.0a1 18:49:04 INFO - mozversion platform_buildid: 20170921003054 18:49:04 INFO - mozversion platform_changeset: f8dd3f21e434be32fe5901849f1723b0e64cf668 18:49:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:49:04 INFO - mozversion platform_version: 57.0a1 18:49:04 INFO - using testdate: 1505958544 18:49:04 INFO - actual date: 1505958544 18:49:04 INFO - starting webserver on 'localhost:50845' 18:49:04 INFO - SUITE-START | Running 2 tests 18:49:04 INFO - TEST-START | dromaeo_css 18:49:04 INFO - Initialising browser for dromaeo_css test... 18:49:04 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50845/getInfo.html -profile /tmp/tmpqxg9_D/profile 18:49:04 INFO - TEST-INFO | started process 3555 (/builds/slave/test/build/application/firefox/firefox http://localhost:50845/getInfo.html) 18:49:09 INFO - TEST-INFO | 3555: exit 0 18:49:09 INFO - Browser initialized. 18:49:09 INFO - Running cycle 1/1 for dromaeo_css test... 18:49:09 INFO - Using env: {'DISPLAY': ':0', 18:49:09 INFO - 'HOME': '/home/cltbld', 18:49:09 INFO - 'JSGC_DISABLE_POISONING': '1', 18:49:09 INFO - 'LANG': 'en_US.UTF-8', 18:49:09 INFO - 'LANGUAGE': 'en_US:en', 18:49:09 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:49:09 INFO - 'LOGNAME': 'cltbld', 18:49:09 INFO - 'MAIL': '/var/mail/cltbld', 18:49:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:49:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:49:09 INFO - 'MOZ_CRASHREPORTER': '1', 18:49:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:09 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:49:09 INFO - 'MOZ_NO_REMOTE': '1', 18:49:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:49:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:09 INFO - 'NO_EM_RESTART': '1', 18:49:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:09 INFO - 'PWD': '/builds/slave/test', 18:49:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:49:09 INFO - 'RUST_BACKTRACE': 'full', 18:49:09 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:49:09 INFO - 'SHELL': '/bin/bash', 18:49:09 INFO - 'SHLVL': '1', 18:49:09 INFO - 'STYLO_FORCE_DISABLED': '1', 18:49:09 INFO - 'TERM': 'linux', 18:49:09 INFO - 'TMOUT': '86400', 18:49:09 INFO - 'USER': 'cltbld', 18:49:09 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1505958484.852817-104608235', 18:49:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:09 INFO - '_': '/tools/buildbot/bin/python'} 18:49:09 INFO - TEST-INFO | started process 3790 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqxg9_D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:49:09 INFO - PID 3790 | 18:49:09 INFO - PID 3790 | (/builds/slave/test/build/application/firefox/firefox:3840): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:49:09 INFO - PID 3790 | 18:49:20 INFO - PID 3790 | RSS: Main: 147443712 18:49:20 INFO - PID 3790 | 18:52:16 INFO - PID 3790 | Cycle 1(1): loaded http://localhost:50845/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50845/tests/dromaeo/cssquery-ext.html) 18:52:18 INFO - PID 3790 | RSS: Main: 148475904 18:52:18 INFO - PID 3790 | 18:55:19 INFO - PID 3790 | Cycle 1(1): loaded http://localhost:50845/tests/dromaeo/cssquery-ext.html (next: http://localhost:50845/tests/dromaeo/cssquery-jquery.html) 18:55:20 INFO - PID 3790 | RSS: Main: 147369984 18:55:20 INFO - PID 3790 | 18:58:22 INFO - PID 3790 | Cycle 1(1): loaded http://localhost:50845/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50845/tests/dromaeo/cssquery-mootools.html) 18:58:22 INFO - PID 3790 | RSS: Main: 149491712 18:58:22 INFO - PID 3790 | 19:01:24 INFO - PID 3790 | Cycle 1(1): loaded http://localhost:50845/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50845/tests/dromaeo/cssquery-prototype.html) 19:01:24 INFO - PID 3790 | RSS: Main: 148353024 19:01:24 INFO - PID 3790 | 19:04:27 INFO - PID 3790 | Cycle 1(1): loaded http://localhost:50845/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50845/tests/dromaeo/cssquery-yui.html) 19:04:28 INFO - PID 3790 | RSS: Main: 149901312 19:04:28 INFO - PID 3790 | 19:07:29 INFO - PID 3790 | Cycle 1(1): loaded http://localhost:50845/tests/dromaeo/cssquery-yui.html (next: http://localhost:50845/tests/dromaeo/cssquery-dojo.html) 19:07:30 INFO - PID 3790 | RSS: Main: 153092096 19:07:30 INFO - PID 3790 | 19:07:30 INFO - PID 3790 | __start_tp_report 19:07:30 INFO - PID 3790 | _x_x_mozilla_page_load 19:07:30 INFO - PID 3790 | _x_x_mozilla_page_load_details 19:07:30 INFO - PID 3790 | |i|pagename|runs| 19:07:30 INFO - PID 3790 | |0;dojo.html;3867.709921755868;3887.416887466878;3898.883033508994;3911.745736527125;3913.2760439318745;6315.526335524843;6354.459870910978;6369.172007639007;6392.6803659817;6462.450691691212;6389.265234498034;6394.072859435379;6448.387403196689;6467.838304042401;6490.123833282505;5704.515116215106;5801.390853960336;5834.912476312858;5842.269716285465;5853.268341457318;95.1893168105333;5477.424870388611;5699.373068962432;5802.042662960606;5807.332156801979;5705.343885453164;5718.1136923776885;5803.245578074865;5806.506446951993;5815.767369305244;5696.943030569682;5725.427457254241;5780.71096445176;5782.884342313172;5792.420757924174;5715.857103572403;5717.256756621654;5790.160426738142;5794.188813566104;5814.709264536757;6157.7514787032;6315.873682526324;6377.808665740035;6398.744050237985;6441.645709485976;6810.591364518095;6815.386615204656;6925.995730619032;6929.064576282188;7008.754693585751;6841.760538381182;6851.45188384928;6852.246252912176;6853.348931851466;6891.483138764613;6493.285738568754;6507.739690412378;6510.251321098087;6511.38141876521;6527.2167339919515;7082.92917070833;7169.533980291264;7176.318249766264;7179.1026121734785;7188.784336469915;6804.863902721918;6808.148981377328;6842;6900.309969003059;6924.376806087476;6830.726770929206;6885.2770459101075;6891.104156459728;6895.55178913369;7036.6481675916;6840.144981877265;6849.280825513349;6862.965685171542;6867.931320686829;6915.4121899638085;6463.741450342028;6815.556988795713;6902.792916212522;6905.309469053054;6981.232064472904;4954.777035033432;4961.925571116436;4967.90064198714;5016.673916195508;5021.799128034838;4456.3538286948215;4496.213162696515;4507.797149128297;4515.841945531891;4537.228671125917;6351.142595749607;6383.872322553616;6386.201724784402;6401.9039714404325;6406.615603063831;6799.660016999131;6876.865317222602;6968.547044442095;6972.6862291196;6985.301469852974;6339.0808332791485;6345.809625711236;6416.03759436079;6427.903581446282;6434.64609446485;5407.891842163135;5509.421510741315;5522;5540.750666220029;5565.359983601857;6995.615241161683;7100.9289907100265;7128.786136415916;7151.106111736033;7173.390261827699;6674.899876501953;6745.460363170934;6760.290169532227;6814.4548436124;6880.827979300558;7200.675969581277;7264.34620884123;7274.345308922222;7285.7449989252245;7290.854182916418;6870.072540207008;6881.071055407656;6937.445004399737;6960.060391847238;6969.407600354027;6795.082663840319;6908;6957.478189135733;6959.96520017417;7069.787906362817;6640.302768209462;6694.631795251308;6710.16122984627;6715.1941766988275;6715.1941766988275;6616.371444712745;6721.966390168018;6750.594964302158;6765.661716914233;6771.729130834711;6589.604623722592;6590.143281373391;6616.867662646817;6659.933400665931;6689.665516724047;6596.373344532261;6753.696083676345;6770.661466926733;6852.588844669336;6871;6444.3233460487845;6512.120863683532;6535.901961470677;6552.082708420729;6554.410103090745 19:07:30 INFO - PID 3790 | |1;ext.html;966.1546147121269;995.7162208770502;1001.6043662753208;1003.3779056984671;1005.4771518810214;41845.95385115374;46428;46459.76770116149;46591.76704116475;46668;43642.12715745683;43770;44027.77986110065;44030.779846100726;44382;7761.611919404036;7803.375729941619;7817;7849.6075196240245;7889.211078892094;7730.729424470144;7771.533707977511;7793.298603125718;7798.337141342992;7807.102183248942;7766.3786897048385;7786.8442631147345;7788.0654321481215;8316.958415207946;8590.484570925733;8518.40371174018;8568.443051201713;8580.056193818706;8583.699570515064;8583.785405364853;8194.918050819475;8393.916060839374;8393.95075615548;8474.915250847474;8480.067192608809;41924.58075419237;42439.78780106111;42484;42492.57507424917;42545.36181957273;3688.6311368863167;3709.7403181777286;3716.1824398632257;3722.032271609401;3724.348239058181;34633.30733385318;34768;34790.304193916236;34846;34854.65145348565;5381.623286369956;5408.729563521848;5415.566754659618;5433.510984011418;5451.209574611698;210414;210584;211280;211584.94207529016;212448;47206.76396617995;47897.52102479;48178.75910620424;52407.47592524102;52424.737876311134;55873;56310.339827911055;56399;57057.14414283789;57106.428935710945;16555.50333489997;16733.665326693397;16873.156342182843;16911.91544042272;16924.661506769797;13662.726745465234;13695.726085478234;13700.93149534246;13711.314434278247;13728.313584320744;14877.851221487646;14885.288191857859;14966.625834354229;14970;15172.544823655307;5808.5933984621515;5817.447342502455;5849.239598852122;5901.3213480450295;5982.521398288127;1068.428390810928;1112.3193104617833;1114.2033446085925;1121.7980763462492;1127.610974213895;25715.35711607225;26079.348016299744;26090.478190436086;26105.608415873776;26422.867885660835;1209.214010892909;1219.2440686774257;1222.2910711787142;1222.3643705273207;1226.834377359045;13514.729705406033;13552.186868787905;13763.655908602364;13784.517541885985;14005.229712365917;274486;275996;276010;276238;276410;59790.40209597848;59900;60017.39982600205;60124;60165;1522.7944227529363;1532.5402379286393;1546.891717579781;1561.1959840682057;1562.2735428025794;25048.874755626104;25198.370040749127;25548;25653;25775.6133657999;41794.79102604467;41858.58141418547;41871;41894.7905260484;41914.58085419107;166978;166999;167778.16110919366;168328;168836;34298;35405.114872128404;37141.071473213386;37199.035114442864;37335.6266437343;49573.25640115329;49611;49873.75063124806;50312;50645.746771265905;35388.11529711778;35409;35613;36252.09369765777;36562.63437365699;35104.648953510135;35379.292414152085;35382.646173537934;35924.28151437009;35929.64070359263;41513;41893.1621367577;42048.789756051025;42059.579404206794;42185.57814421816;34035.65964340325;34145.48781768226;34236.14409639779;34552.48171277483;34795.82602087074;25812.22563323103;25924.35189120287;26320.473590528465;26323.605145922444;26758.464830703666 19:07:30 INFO - PID 3790 | |2;jquery.html;613.5459759777766;621.5711159300082;623.2707731953615;624.2321944008869;630.3727790848104;23124.65313020306;23461.41346466339;23939.64090538639;23964;24023.879880600594;23471.765282347173;23932.641010384814;24156.63765043526;24214.15250466234;24254;6718.160229971254;6899.793006209822;6905.896411553806;6912.239653638096;6914.239433662293;6398.424141827235;6639.5684280521855;6690.431313338372;6711.094002309675;6841.760538381158;6653.500987425939;6655.500837437212;6663.666816659172;6665.266820649725;6666.866662666744;6383.808485745435;6637.303083176245;6656.600603963753;6658.833529161761;6659.3340665933265;6265.342139075423;6268.059791031336;6271.404216599416;6271.780487682955;6271.937280627181;30280.697193028227;30825.84587077073;30920.226994325323;30941;30965.845170774002;2234.5866014787316;2260.107257633242;2261.298997310839;2261.3329067925;2263.1626298269744;20911.790882091136;21348.78651213499;21378.572428551342;21415.250466233763;21476.140954361807;2488.25352394281;2544.211294498711;2575.8969641214517;2591.054265193191;2606.5568853294985;141292;147071;148763;154400;155262.22368888196;15848.92075539615;16148.27332770028;16539;17103.572410689583;17109.401170958958;26654;26690.466190676078;26690.866545667537;26725.732742672324;26753.598696019577;10222.575437228612;10331.22515811302;10455;10499.527521261562;10525.21060920434;9131.81736365269;9205.125513076247;9267.582958766738;9356.578953947088;9400.059994000681;9801.019898010141;9805.55874985613;9874.654387096418;9974.351667141613;10178.541965611565;2799.860006999642;2829.2078218098964;2849.4016256585933;2904.8838046477904;3007.5488676698233;1045.8692663417073;1049.821530339844;1062.5802807890839;1085.2511766880828;1087.8803331633515;21746.238881639067;22139.667904981437;22329.42246839394;22367.888160559094;22572.322830315115;1100.779844031197;1124.960626378073;1125.1673761416494;1127.4926283172606;1142.4344949250174;11788.646340609796;12116.454759535842;12137.696557585954;12218.694532636577;12224.388780560936;552338;555941;568340;568952;577110;122317;124730;127330;127819;128561;1226.466487078124;1243.1173866554848;1265.2598230035533;1268.315109840694;1271.0975207602705;19706.704399433733;19819.702704459734;19974.90012549928;20094.095765690286;20207.69688454645;59586;60582.39417605768;60785;62568.37431625625;63294.36705632885;79305;79313.2068679329;79624.20375796167;80897;81678;15811.367545298059;16133.435329763402;16174.353025879303;16269.51191464258;16297.837021629632;24735.50528989446;24858;24923;24955.376115597257;25057.498850022534;19207.423777286705;20140.697889531963;20187.79812201859;20233;20503.794962050786;17870.821291786917;17899.37352192667;18221.45335639933;18269;18541.35105271309;20109.19563217455;20453.795462045786;20454.18183272712;20508.179672813516;20580.794192058485;15818.367265309258;15917.761233581274;16020.759688604914;16068.517944461684;16114.919425402797;19795.505112372306;20000.799991999895;20036.59926801485;20058.39824805258;20288 19:07:30 INFO - PID 3790 | |3;mootools.html;1385.8336999560054;1473.27809373407;1499.3777582303344;1504.217806740495;1512.0625212368334;16754.413595524144;17437;17446.563835904108;17459.476215713516;17464.214110365047;16798.412055578057;16851.66296674069;16868.156592170373;16927.661446771057;16961.236744346505;2756.2558109310476;2763.2539214412095;2835.7306055924655;2842.4173044525824;2845.6727476340175;2779.7359250871136;2828.0667379764627;2839.8154119982237;2853.5862299966493;2858.6992918222263;2752.174347695693;2832.4335132973383;2838.5458326667735;2840.4745122152467;2840.8011439199263;2792.8603569821425;2805.382815780535;2811.732885375886;2818.5208514552573;2822.47784159929;2601.349662584354;2694.420699549606;2694.757471827545;2695.9865200674067;2705.337192387858;16939.661206775796;17078.23147958345;17080;17092.829071709373;17105.14474276281;8303.626336814857;8634.093420190853;8641.179087986631;8646.783830404227;8648.913510864873;5366.356037275513;5451.100568406208;5563.833085007456;5622.297212848394;5655.293088363955;2071.4200023993308;2093.95812083759;2098.244631932503;2105.95788084239;2130.062772380163;30444;30591.08226753201;30630;30635.846820765753;30683.386332273454;7090.680919358538;7193.784186474414;7255.347018768336;7307.853842923112;7357.632118394059;19943.401697949084;19973.500662483264;19975.900120499304;20032.799672003384;20040;7890.210978902064;8110.107888132301;8137.389695772841;8150.307223885918;8153.714619988391;7279.781606551812;7378.077740282465;7378.225286344964;7408.629568521553;7506.28690274423;5340.706261155596;5548.742185396826;5570.41510641385;5582.134769110794;5611.971940140274;1501.7597184450435;1517.9165145917082;1518.5975716435153;1532.9616759580877;1556.4163438710484;958.5973890965811;965.7541771115323;975.2990585378408;992.161623428202;998.3959704378747;8230.300424463869;8260.586970651444;8304.792380190424;8305.211004954519;8337.082920878698;796.8725003999332;800.4596897094438;816.7713040348712;819.2135549872071;826.1490664615455;5440.510354068153;6050.606710563888;6140.5701600887505;6267.1539342189135;6292.4966002719675;22945.77054229436;24336.391590210387;24971.62542561863;25514.489710206064;25572.616410753853;24224.87887560551;24864.751352486244;24871.875640622042;25319.240422787345;25398;12147.453364598614;12232.816507752214;12486.687832804253;12562.937185314198;12565.246085234916;9171.44971301724;9338.626454941998;9364.765880853032;9399.530023498934;9434.764130896783;20739.170433182502;20847.270345537832;21097.683534746684;21255.893720531298;21619.35141945744;21837.672434913173;21967.23114690978;22092.779072209716;22117.77882221157;22190.778092219516;12451.937740311241;12726.363681816058;12743.49026038948;12758;12763.298018609066;15645.530634080997;15737.134457604941;15739.527814165593;15987.760183597024;16000.599985000468;12568.30874301922;12749.107562470537;12804.167729097579;13074.738505229652;13301.068925175145;12367;12446.128770985944;12449.37753112359;12795.296258705488;12812.935935320636;10874.945625271823;10894.78210435771;10917.508712107816;11143.16426268017;11146;8073.959630201812;8160.306373958043;8160.673573057249;8233.588320583827;8272.545010024507;8619.91380086191;8731.65073397057;8736.213740763362;8800.251978581893;8842.557872106498 19:07:30 INFO - PID 3790 | |4;prototype.html;543.9347278326601;565.2228186243915;571.8055861007257;575.1057106199863;594.129600135801;6300.558960872735;6381;6463.511548053998;6471.644059576722;6483.675816209195;6174.506039516839;6386.201724784402;6386.936130638692;6406.327335629761;6431.549791514596;5217.556507696831;5222.216667499886;5222.32109825724;5240.528352448279;5240.842774716764;4967.801287948478;5126.92309615356;5212.556932660728;5220.530152286294;5251.687078230442;5079.669821461612;5081.263216833557;5096.108181068316;5101.183810590306;5108.514691104339;5040.420351659551;5041.294218809369;5067.265246539231;5078.085944530001;5087.287779710843;4595.839145629887;4845.345878306419;4925.916298414343;4992.650514463989;5001.724905130216;6349.8412539686415;6353.523485738542;6359.491240700733;6366.87266254677;6380.61716297019;6278.937210627881;6288.654124023177;6307.9684601577155;6345.9365406345805;6363.268224154189;6936.861262774767;6939.356837660218;6942.236354001056;6953.478489113337;6965.686544105477;5623.397331760429;5639.943600563983;5640.597921359245;5645.971770141123;5661.60368774186;6935.826604334881;6973.686184121676;7044.859102817915;7108.218096009435;7128.180259270147;5770.538356931435;5801.767929282782;5816.41835816415;5826.989688557441;5844.444777746107;5909.349971503131;5920;5923.526117910643;5938.28740551128;5945.989181839098;6246.562740608114;6277.843053923689;6281.748730050746;6296.7721294348075;6313.715882785285;6866.931330686629;6877.65611719412;6886.724531018703;6889.311068893071;6901.482388820858;2045.703373010935;2069.7102405663218;2078.251829341436;2083.864548804323;2090.6863970404256;1495.3719437836135;1557.909463375619;1559.3528685595352;1568.8352722964153;1577.7869987551762;6232.657203853832;6281.654509002049;6281.654509002049;6282.560220784501;6343.27052388972;6884.965575172092;6885.827854303582;6891.517593768388;6910.997905303803;6914.792556223321;6238.313785483593;6295.401936815995;6312.5581209314905;6316.30520642729;6385.872282554323;3611.2777444511203;3618.819059047037;3628.818559072036;3638.69071128957;3662.8901132966053;7007.544509606962;7087.397571206505;7107.715691372287;7116.359527642434;7182.60495672743;7042.753503627348;7058.447141628833;7074.398676112485;7080.2919708028785;7187.31720486553;7163.570185788765;7183.389411899942;7196.748113815991;7206.207317195104;7219.747308844376;6878.759243426456;6882.518223724492;6930.930690693029;6939.236683964861;6953.721851125898;6863.485238607224;6884.380405763505;6916.371778724053;6930;6942.340477654615;7150.141982962078;7210.315020073084;7224.638768061471;7233.493655444279;7247.565146091251;5801.738921748616;5802.912956305574;5823.708814559171;5851.795187168429;5874.118882167709;6744.763933262312;6767.39093481589;6986.043907705851;6989.930100698928;7063.117110361205;6609.834754131185;6613.537052406477;6640.945165223858;6692.230393504809;6724.596524208563;6582.539222254396;6611.834704132435;6612.437942774918;6672.466202703869;6693.497987650849;6423.29343772194;6539.716485701872;6571.901421478586;6609.339066093352;6613.924525095661;6524.543281970216;6550.689862027709;6597.505187111082;6693.564918280296;6734.730610775514;6375;6523.804285871432;6525.738970441129;6548.1814773153355;6563.573367731082 19:07:30 INFO - PID 3790 | |5;yui.html;970.9271804614656;988.4563490080455;999.4003597841296;1005.9245556583263;1023.2019025160375;19320.613587728236;19644;19672.60654786907;19673.31143409981;19723.309684161057;18474.630507389844;18556;18666.06669666518;19592.706109408333;19767.209311627517;1032.8244198486257;1060.5757696921216;1060.6870973062964;1061.2147011211725;1061.5488417422603;1062.1927335225207;1081.8809930907632;1083.030687534656;1093.907017903479;1109.7724966381913;1166.8133098704209;1171.0690001448868;1171.1743221029196;1171.226990186477;1171.3850228629994;1150.5742875136211;1155.5262342439644;1162.697698598362;1164.5865717670274;1168;851.638053827121;852.910444403335;856.8029353248726;857.3869683176558;858.3176374782063;20040.298589549442;20725.481862953395;21555.1377944882;21884.01521931501;21923.890380547997;22963.885180574158;23186;23626;23636.054557817668;24186.516269674685;17019.149042547822;17371.30514779407;17420.30318787247;17529.912350438295;17636.38272660451;3983.322835118037;4059;4059.634632883025;4063.3904914262803;4064.1465292288653;137962;147028;147514.99999999892;149725;160861;17879.28482860697;17938.461846144637;18385.81614183841;18392.172352244183;18454;33942.830285848904;33970.49044264338;34081;35298.64701352954;35587.4661880072;9397.812043759086;9550.522473876417;9560.952195239119;9562.043795620382;9579.521023948775;4390.626796722315;4455.86632401022;4458.9331160032625;4549.749763763025;4556.11155824611;5865.560082993792;6100.3899610039525;6101.084837274444;6101.786437474667;6122.969385153046;1224.4184012593946;1249.056961993689;1262.1480500662017;1265.531753251303;1272.63093702827;673.6699017481399;684.4626967830229;689.19020151323;696.1993707236718;699.9160100787836;12143.757124857453;13011.804822927663;13418;14031.859681403259;14118;726.1757904778009;740.637087826961;748.5059860492158;750.9286617771303;754.6943487887414;5860.2381690380835;5882.382349853204;5920.526357891358;6096.908546371808;6098.603590766586;144892.27553862164;147472;147505;149128;150046.24976875263;127226.36386818007;128475.07944888767;128620;130289;133809.33095334462;107208;110760;113793;114065.42967285277;114766.42616787029;11147.557352904725;11161;11180.23595724575;11310.94344528272;11492.195546312012;55051;55228;55787.44212557985;55914;56147.71926140343;71824.28175718385;72357;72561.6371918137;72654.27345726475;72682.63658681673;14963.551093467213;15681.607959801097;15720.528384148494;15832.208389580705;15919.044857308598;18806.52983675419;19235.90382048137;19322.516937076685;19336.70994935049;19436.90281548583;16700.665986679956;16750.66498670044;16761.1619419031;16832.07423591665;17107.315707371567;17427.128643568023;17708;17721.556961076076;17826.46520604384;18014.5496362592;17042.829571704126;17110.315587376364;17113.657726845642;17135.914320428816;17246.741298880777;11809;11878.643640690447;11925.10561707858;11928.224665396721;12380.133390662928;12397.380130993595;12460.750784984068;12538.623841284776;12663;12670.556530521388 19:07:30 INFO - PID 3790 | __end_tp_report 19:07:30 INFO - PID 3790 | __start_cc_report 19:07:30 INFO - PID 3790 | _x_x_mozilla_cycle_collect,257 19:07:30 INFO - PID 3790 | __end_cc_report 19:07:30 INFO - PID 3790 | __startTimestamp1505959650189__endTimestamp 19:07:30 INFO - PID 3790 | 19:07:30 INFO - PID 3790 | ------- Summary: start ------- 19:07:30 INFO - PID 3790 | Number of tests: 6 19:07:30 INFO - PID 3790 | 19:07:30 INFO - PID 3790 | [#0] dojo.html Cycles:165 Average:6322.43 Median:6596.37 stddev:907.99 (13.8%) stddev-sans-first:890.11 19:07:30 INFO - PID 3790 | Values: 3867.7 3887.4 3898.9 3911.7 3913.3 6315.5 6354.5 6369.2 6392.7 6462.5 6389.3 6394.1 6448.4 6467.8 6490.1 5704.5 5801.4 5834.9 5842.3 5853.3 95.2 5477.4 5699.4 5802.0 5807.3 5705.3 5718.1 5803.2 5806.5 5815.8 5696.9 5725.4 5780.7 5782.9 5792.4 5715.9 5717.3 5790.2 5794.2 5814.7 6157.8 6315.9 6377.8 6398.7 6441.6 6810.6 6815.4 6926.0 6929.1 7008.8 6841.8 6851.5 6852.2 6853.3 6891.5 6493.3 6507.7 6510.3 6511.4 6527.2 7082.9 7169.5 7176.3 7179.1 7188.8 6804.9 6808.1 6842.0 6900.3 6924.4 6830.7 6885.3 6891.1 6895.6 7036.6 6840.1 6849.3 6863.0 6867.9 6915.4 6463.7 6815.6 6902.8 6905.3 6981.2 4954.8 4961.9 4967.9 5016.7 5021.8 4456.4 4496.2 4507.8 4515.8 4537.2 6351.1 6383.9 6386.2 6401.9 6406.6 6799.7 6876.9 6968.5 6972.7 6985.3 6339.1 6345.8 6416.0 6427.9 6434.6 5407.9 5509.4 5522.0 5540.8 5565.4 6995.6 7100.9 7128.8 7151.1 7173.4 6674.9 6745.5 6760.3 6814.5 6880.8 7200.7 7264.3 7274.3 7285.7 7290.9 6870.1 6881.1 6937.4 6960.1 6969.4 6795.1 6908.0 6957.5 6960.0 7069.8 6640.3 6694.6 6710.2 6715.2 6715.2 6616.4 6722.0 6750.6 6765.7 6771.7 6589.6 6590.1 6616.9 6659.9 6689.7 6596.4 6753.7 6770.7 6852.6 6871.0 6444.3 6512.1 6535.9 6552.1 6554.4 19:07:30 INFO - PID 3790 | 19:07:30 INFO - PID 3790 | [#1] ext.html Cycles:180 Average:40584.20 Median:3699.19 stddev:57951.41 (1566.6%) stddev-sans-first:58037.63 19:07:30 INFO - PID 3790 | Values: 966.2 995.7 1001.6 1003.4 1005.5 41846.0 46428.0 46459.8 46591.8 46668.0 43642.1 43770.0 44027.8 44030.8 44382.0 7761.6 7803.4 7817.0 7849.6 7889.2 7730.7 7771.5 7793.3 7798.3 7807.1 7766.4 7786.8 7788.1 8317.0 8590.5 8518.4 8568.4 8580.1 8583.7 8583.8 8194.9 8393.9 8394.0 8474.9 8480.1 41924.6 42439.8 42484.0 42492.6 42545.4 3688.6 3709.7 3716.2 3722.0 3724.3 34633.3 34768.0 34790.3 34846.0 34854.7 5381.6 5408.7 5415.6 5433.5 5451.2 210414.0 210584.0 211280.0 211584.9 212448.0 47206.8 47897.5 48178.8 52407.5 52424.7 55873.0 56310.3 56399.0 57057.1 57106.4 16555.5 16733.7 16873.2 16911.9 16924.7 13662.7 13695.7 13700.9 13711.3 13728.3 14877.9 14885.3 14966.6 14970.0 15172.5 5808.6 5817.4 5849.2 5901.3 5982.5 1068.4 1112.3 1114.2 1121.8 1127.6 25715.4 26079.3 26090.5 26105.6 26422.9 1209.2 1219.2 1222.3 1222.4 1226.8 13514.7 13552.2 13763.7 13784.5 14005.2 274486.0 275996.0 276010.0 276238.0 276410.0 59790.4 59900.0 60017.4 60124.0 60165.0 1522.8 1532.5 1546.9 1561.2 1562.3 25048.9 25198.4 25548.0 25653.0 25775.6 41794.8 41858.6 41871.0 41894.8 41914.6 166978.0 166999.0 167778.2 168328.0 168836.0 34298.0 35405.1 37141.1 37199.0 37335.6 49573.3 49611.0 49873.8 50312.0 50645.7 35388.1 35409.0 35613.0 36252.1 36562.6 35104.6 35379.3 35382.6 35924.3 35929.6 41513.0 41893.2 42048.8 42059.6 42185.6 34035.7 34145.5 34236.1 34552.5 34795.8 25812.2 25924.4 26320.5 26323.6 26758.5 19:07:30 INFO - PID 3790 | 19:07:30 INFO - PID 3790 | [#2] jquery.html Cycles:180 Average:38584.54 Median:12693.91 stddev:94754.29 (746.5%) stddev-sans-first:94977.21 19:07:30 INFO - PID 3790 | Values: 613.5 621.6 623.3 624.2 630.4 23124.7 23461.4 23939.6 23964.0 24023.9 23471.8 23932.6 24156.6 24214.2 24254.0 6718.2 6899.8 6905.9 6912.2 6914.2 6398.4 6639.6 6690.4 6711.1 6841.8 6653.5 6655.5 6663.7 6665.3 6666.9 6383.8 6637.3 6656.6 6658.8 6659.3 6265.3 6268.1 6271.4 6271.8 6271.9 30280.7 30825.8 30920.2 30941.0 30965.8 2234.6 2260.1 2261.3 2261.3 2263.2 20911.8 21348.8 21378.6 21415.3 21476.1 2488.3 2544.2 2575.9 2591.1 2606.6 141292.0 147071.0 148763.0 154400.0 155262.2 15848.9 16148.3 16539.0 17103.6 17109.4 26654.0 26690.5 26690.9 26725.7 26753.6 10222.6 10331.2 10455.0 10499.5 10525.2 9131.8 9205.1 9267.6 9356.6 9400.1 9801.0 9805.6 9874.7 9974.4 10178.5 2799.9 2829.2 2849.4 2904.9 3007.5 1045.9 1049.8 1062.6 1085.3 1087.9 21746.2 22139.7 22329.4 22367.9 22572.3 1100.8 1125.0 1125.2 1127.5 1142.4 11788.6 12116.5 12137.7 12218.7 12224.4 552338.0 555941.0 568340.0 568952.0 577110.0 122317.0 124730.0 127330.0 127819.0 128561.0 1226.5 1243.1 1265.3 1268.3 1271.1 19706.7 19819.7 19974.9 20094.1 20207.7 59586.0 60582.4 60785.0 62568.4 63294.4 79305.0 79313.2 79624.2 80897.0 81678.0 15811.4 16133.4 16174.4 16269.5 16297.8 24735.5 24858.0 24923.0 24955.4 25057.5 19207.4 20140.7 20187.8 20233.0 20503.8 17870.8 17899.4 18221.5 18269.0 18541.4 20109.2 20453.8 20454.2 20508.2 20580.8 15818.4 15917.8 16020.8 16068.5 16114.9 19795.5 20000.8 20036.6 20058.4 20288.0 19:07:30 INFO - PID 3790 | 19:07:30 INFO - PID 3790 | [#3] mootools.html Cycles:180 Average:10438.06 Median:2754.22 stddev:7759.99 (281.7%) stddev-sans-first:7751.96 19:07:30 INFO - PID 3790 | Values: 1385.8 1473.3 1499.4 1504.2 1512.1 16754.4 17437.0 17446.6 17459.5 17464.2 16798.4 16851.7 16868.2 16927.7 16961.2 2756.3 2763.3 2835.7 2842.4 2845.7 2779.7 2828.1 2839.8 2853.6 2858.7 2752.2 2832.4 2838.5 2840.5 2840.8 2792.9 2805.4 2811.7 2818.5 2822.5 2601.3 2694.4 2694.8 2696.0 2705.3 16939.7 17078.2 17080.0 17092.8 17105.1 8303.6 8634.1 8641.2 8646.8 8648.9 5366.4 5451.1 5563.8 5622.3 5655.3 2071.4 2094.0 2098.2 2106.0 2130.1 30444.0 30591.1 30630.0 30635.8 30683.4 7090.7 7193.8 7255.3 7307.9 7357.6 19943.4 19973.5 19975.9 20032.8 20040.0 7890.2 8110.1 8137.4 8150.3 8153.7 7279.8 7378.1 7378.2 7408.6 7506.3 5340.7 5548.7 5570.4 5582.1 5612.0 1501.8 1517.9 1518.6 1533.0 1556.4 958.6 965.8 975.3 992.2 998.4 8230.3 8260.6 8304.8 8305.2 8337.1 796.9 800.5 816.8 819.2 826.1 5440.5 6050.6 6140.6 6267.2 6292.5 22945.8 24336.4 24971.6 25514.5 25572.6 24224.9 24864.8 24871.9 25319.2 25398.0 12147.5 12232.8 12486.7 12562.9 12565.2 9171.4 9338.6 9364.8 9399.5 9434.8 20739.2 20847.3 21097.7 21255.9 21619.4 21837.7 21967.2 22092.8 22117.8 22190.8 12451.9 12726.4 12743.5 12758.0 12763.3 15645.5 15737.1 15739.5 15987.8 16000.6 12568.3 12749.1 12804.2 13074.7 13301.1 12367.0 12446.1 12449.4 12795.3 12812.9 10874.9 10894.8 10917.5 11143.2 11146.0 8074.0 8160.3 8160.7 8233.6 8272.5 8619.9 8731.7 8736.2 8800.3 8842.6 19:07:30 INFO - PID 3790 | 19:07:30 INFO - PID 3790 | [#4] prototype.html Cycles:180 Average:5857.09 Median:6377.81 stddev:1568.40 (24.6%) stddev-sans-first:1521.25 19:07:30 INFO - PID 3790 | Values: 543.9 565.2 571.8 575.1 594.1 6300.6 6381.0 6463.5 6471.6 6483.7 6174.5 6386.2 6386.9 6406.3 6431.5 5217.6 5222.2 5222.3 5240.5 5240.8 4967.8 5126.9 5212.6 5220.5 5251.7 5079.7 5081.3 5096.1 5101.2 5108.5 5040.4 5041.3 5067.3 5078.1 5087.3 4595.8 4845.3 4925.9 4992.7 5001.7 6349.8 6353.5 6359.5 6366.9 6380.6 6278.9 6288.7 6308.0 6345.9 6363.3 6936.9 6939.4 6942.2 6953.5 6965.7 5623.4 5639.9 5640.6 5646.0 5661.6 6935.8 6973.7 7044.9 7108.2 7128.2 5770.5 5801.8 5816.4 5827.0 5844.4 5909.3 5920.0 5923.5 5938.3 5946.0 6246.6 6277.8 6281.7 6296.8 6313.7 6866.9 6877.7 6886.7 6889.3 6901.5 2045.7 2069.7 2078.3 2083.9 2090.7 1495.4 1557.9 1559.4 1568.8 1577.8 6232.7 6281.7 6281.7 6282.6 6343.3 6885.0 6885.8 6891.5 6911.0 6914.8 6238.3 6295.4 6312.6 6316.3 6385.9 3611.3 3618.8 3628.8 3638.7 3662.9 7007.5 7087.4 7107.7 7116.4 7182.6 7042.8 7058.4 7074.4 7080.3 7187.3 7163.6 7183.4 7196.7 7206.2 7219.7 6878.8 6882.5 6930.9 6939.2 6953.7 6863.5 6884.4 6916.4 6930.0 6942.3 7150.1 7210.3 7224.6 7233.5 7247.6 5801.7 5802.9 5823.7 5851.8 5874.1 6744.8 6767.4 6986.0 6989.9 7063.1 6609.8 6613.5 6640.9 6692.2 6724.6 6582.5 6611.8 6612.4 6672.5 6693.5 6423.3 6539.7 6571.9 6609.3 6613.9 6524.5 6550.7 6597.5 6693.6 6734.7 6375.0 6523.8 6525.7 6548.2 6563.6 19:07:30 INFO - PID 3790 | 19:07:30 INFO - PID 3790 | [#5] yui.html Cycles:180 Average:27627.82 Median:17852.88 stddev:40991.51 (229.6%) stddev-sans-first:41057.63 19:07:30 INFO - PID 3790 | Values: 970.9 988.5 999.4 1005.9 1023.2 19320.6 19644.0 19672.6 19673.3 19723.3 18474.6 18556.0 18666.1 19592.7 19767.2 1032.8 1060.6 1060.7 1061.2 1061.5 1062.2 1081.9 1083.0 1093.9 1109.8 1166.8 1171.1 1171.2 1171.2 1171.4 1150.6 1155.5 1162.7 1164.6 1168.0 851.6 852.9 856.8 857.4 858.3 20040.3 20725.5 21555.1 21884.0 21923.9 22963.9 23186.0 23626.0 23636.1 24186.5 17019.1 17371.3 17420.3 17529.9 17636.4 3983.3 4059.0 4059.6 4063.4 4064.1 137962.0 147028.0 147515.0 149725.0 160861.0 17879.3 17938.5 18385.8 18392.2 18454.0 33942.8 33970.5 34081.0 35298.6 35587.5 9397.8 9550.5 9561.0 9562.0 9579.5 4390.6 4455.9 4458.9 4549.7 4556.1 5865.6 6100.4 6101.1 6101.8 6123.0 1224.4 1249.1 1262.1 1265.5 1272.6 673.7 684.5 689.2 696.2 699.9 12143.8 13011.8 13418.0 14031.9 14118.0 726.2 740.6 748.5 750.9 754.7 5860.2 5882.4 5920.5 6096.9 6098.6 144892.3 147472.0 147505.0 149128.0 150046.2 127226.4 128475.1 128620.0 130289.0 133809.3 107208.0 110760.0 113793.0 114065.4 114766.4 11147.6 11161.0 11180.2 11310.9 11492.2 55051.0 55228.0 55787.4 55914.0 56147.7 71824.3 72357.0 72561.6 72654.3 72682.6 14963.6 15681.6 15720.5 15832.2 15919.0 18806.5 19235.9 19322.5 19336.7 19436.9 16700.7 16750.7 16761.2 16832.1 17107.3 17427.1 17708.0 17721.6 17826.5 18014.5 17042.8 17110.3 17113.7 17135.9 17246.7 11809.0 11878.6 11925.1 11928.2 12380.1 12397.4 12460.8 12538.6 12663.0 12670.6 19:07:30 INFO - PID 3790 | -------- Summary: end -------- 19:07:30 INFO - PID 3790 | 19:07:30 INFO - TEST-INFO | 3790: exit 0 19:07:30 INFO - TEST-OK | dromaeo_css | took 1106702ms 19:07:30 INFO - TEST-START | kraken 19:07:30 INFO - Initialising browser for kraken test... 19:07:30 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50845/getInfo.html -profile /tmp/tmpOXeHqf/profile 19:07:30 INFO - TEST-INFO | started process 4158 (/builds/slave/test/build/application/firefox/firefox http://localhost:50845/getInfo.html) 19:07:35 INFO - TEST-INFO | 4158: exit 0 19:07:35 INFO - Browser initialized. 19:07:35 INFO - Running cycle 1/1 for kraken test... 19:07:35 INFO - Using env: {'DISPLAY': ':0', 19:07:35 INFO - 'HOME': '/home/cltbld', 19:07:35 INFO - 'JSGC_DISABLE_POISONING': '1', 19:07:35 INFO - 'LANG': 'en_US.UTF-8', 19:07:35 INFO - 'LANGUAGE': 'en_US:en', 19:07:35 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 19:07:35 INFO - 'LOGNAME': 'cltbld', 19:07:35 INFO - 'MAIL': '/var/mail/cltbld', 19:07:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:07:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:07:35 INFO - 'MOZ_CRASHREPORTER': '1', 19:07:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:35 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 19:07:35 INFO - 'MOZ_NO_REMOTE': '1', 19:07:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:07:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:35 INFO - 'NO_EM_RESTART': '1', 19:07:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:35 INFO - 'PWD': '/builds/slave/test', 19:07:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:07:35 INFO - 'RUST_BACKTRACE': 'full', 19:07:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:07:35 INFO - 'SHELL': '/bin/bash', 19:07:35 INFO - 'SHLVL': '1', 19:07:35 INFO - 'STYLO_FORCE_DISABLED': '1', 19:07:35 INFO - 'TERM': 'linux', 19:07:35 INFO - 'TMOUT': '86400', 19:07:35 INFO - 'USER': 'cltbld', 19:07:35 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1505958484.852817-104608235', 19:07:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:35 INFO - '_': '/tools/buildbot/bin/python'} 19:07:35 INFO - TEST-INFO | started process 4390 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOXeHqf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:07:36 INFO - PID 4390 | 19:07:36 INFO - PID 4390 | (/builds/slave/test/build/application/firefox/firefox:4439): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:07:36 INFO - PID 4390 | 19:07:46 INFO - PID 4390 | RSS: Main: 149471232 19:07:46 INFO - PID 4390 | 19:07:49 INFO - PID 4390 | Cycle 1(1): loaded http://localhost:50845/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50845/tests/kraken/driver.html?testName=audio-beat-detection) 19:07:50 INFO - PID 4390 | RSS: Main: 154710016 19:07:50 INFO - PID 4390 | 19:07:52 INFO - PID 4390 | Cycle 1(1): loaded http://localhost:50845/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50845/tests/kraken/driver.html?testName=audio-dft) 19:07:53 INFO - PID 4390 | RSS: Main: 154828800 19:07:53 INFO - PID 4390 | 19:07:56 INFO - PID 4390 | Cycle 1(1): loaded http://localhost:50845/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50845/tests/kraken/driver.html?testName=audio-fft) 19:07:56 INFO - PID 4390 | RSS: Main: 151240704 19:07:56 INFO - PID 4390 | 19:07:58 INFO - PID 4390 | Cycle 1(1): loaded http://localhost:50845/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50845/tests/kraken/driver.html?testName=audio-oscillator) 19:07:59 INFO - PID 4390 | RSS: Main: 152588288 19:07:59 INFO - PID 4390 | 19:08:01 INFO - PID 4390 | Cycle 1(1): loaded http://localhost:50845/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50845/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:08:01 INFO - PID 4390 | RSS: Main: 152129536 19:08:01 INFO - PID 4390 | 19:08:05 INFO - PID 4390 | Cycle 1(1): loaded http://localhost:50845/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50845/tests/kraken/driver.html?testName=imaging-darkroom) 19:08:05 INFO - PID 4390 | RSS: Main: 152182784 19:08:05 INFO - PID 4390 | 19:08:09 INFO - PID 4390 | Cycle 1(1): loaded http://localhost:50845/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50845/tests/kraken/driver.html?testName=imaging-desaturate) 19:08:09 INFO - PID 4390 | RSS: Main: 153350144 19:08:09 INFO - PID 4390 | 19:08:12 INFO - PID 4390 | Cycle 1(1): loaded http://localhost:50845/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50845/tests/kraken/driver.html?testName=json-parse-financial) 19:08:13 INFO - PID 4390 | RSS: Main: 155287552 19:08:13 INFO - PID 4390 | 19:08:14 INFO - PID 4390 | Cycle 1(1): loaded http://localhost:50845/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50845/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:08:15 INFO - PID 4390 | RSS: Main: 155541504 19:08:15 INFO - PID 4390 | 19:08:16 INFO - PID 4390 | Cycle 1(1): loaded http://localhost:50845/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50845/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:08:17 INFO - PID 4390 | RSS: Main: 155713536 19:08:17 INFO - PID 4390 | 19:08:19 INFO - PID 4390 | Cycle 1(1): loaded http://localhost:50845/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50845/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:08:20 INFO - PID 4390 | RSS: Main: 152702976 19:08:20 INFO - PID 4390 | 19:08:22 INFO - PID 4390 | Cycle 1(1): loaded http://localhost:50845/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50845/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:08:23 INFO - PID 4390 | RSS: Main: 153030656 19:08:23 INFO - PID 4390 | 19:08:25 INFO - PID 4390 | Cycle 1(1): loaded http://localhost:50845/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50845/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:08:26 INFO - PID 4390 | RSS: Main: 153202688 19:08:26 INFO - PID 4390 | 19:08:28 INFO - PID 4390 | Cycle 1(1): loaded http://localhost:50845/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50845/tests/kraken/driver.html?testName=ai-astar) 19:08:28 INFO - PID 4390 | RSS: Main: 154755072 19:08:28 INFO - PID 4390 | 19:08:28 INFO - PID 4390 | __start_tp_report 19:08:28 INFO - PID 4390 | _x_x_mozilla_page_load 19:08:28 INFO - PID 4390 | _x_x_mozilla_page_load_details 19:08:28 INFO - PID 4390 | |i|pagename|runs| 19:08:28 INFO - PID 4390 | |0;ai-astar;106;106;105;105;105;107;106;106;105;106 19:08:28 INFO - PID 4390 | |1;audio-beat-detection;113;113;114;112;115;111;117;111;116;123 19:08:28 INFO - PID 4390 | |2;audio-dft;163;165;163;163;163;163;161;162;162;162 19:08:28 INFO - PID 4390 | |3;audio-fft;74;73;77;73;73;76;73;72;73;73 19:08:28 INFO - PID 4390 | |4;audio-oscillator;77;77;77;77;77;81;81;82;78;78 19:08:28 INFO - PID 4390 | |5;imaging-gaussian-blur;115;114;113;113;114;113;114;113;114;115 19:08:28 INFO - PID 4390 | |6;imaging-darkroom;149;153;150;152;150;149;149;149;149;150 19:08:28 INFO - PID 4390 | |7;imaging-desaturate;94;94;94;99;93;93;94;93;94;94 19:08:28 INFO - PID 4390 | |8;json-parse-financial;45;44;47;48;50;49;45;45;48;45 19:08:28 INFO - PID 4390 | |9;json-stringify-tinderbox;37;38;37;38;37;38;37;38;37;37 19:08:28 INFO - PID 4390 | |10;stanford-crypto-aes;71;72;73;70;74;69;69;72;69;69 19:08:28 INFO - PID 4390 | |11;stanford-crypto-ccm;104;100;103;110;109;104;112;115;110;107 19:08:28 INFO - PID 4390 | |12;stanford-crypto-pbkdf2;160;152;149;151;152;158;148;151;150;149 19:08:28 INFO - PID 4390 | |13;stanford-crypto-sha256-iterative;62;60;58;60;58;60;69;61;59;60 19:08:28 INFO - PID 4390 | __end_tp_report 19:08:28 INFO - PID 4390 | __start_cc_report 19:08:28 INFO - PID 4390 | _x_x_mozilla_cycle_collect,701 19:08:28 INFO - PID 4390 | __end_cc_report 19:08:28 INFO - PID 4390 | __startTimestamp1505959708447__endTimestamp 19:08:28 INFO - PID 4390 | 19:08:28 INFO - PID 4390 | ------- Summary: start ------- 19:08:28 INFO - PID 4390 | Number of tests: 14 19:08:28 INFO - PID 4390 | 19:08:28 INFO - PID 4390 | [#0] ai-astar Cycles:10 Average:105.70 Median:106.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 19:08:28 INFO - PID 4390 | Values: 106.0 106.0 105.0 105.0 105.0 107.0 106.0 106.0 105.0 106.0 19:08:28 INFO - PID 4390 | 19:08:28 INFO - PID 4390 | [#1] audio-beat-detection Cycles:10 Average:114.50 Median:114.50 stddev:3.60 (3.1%) stddev-sans-first:3.77 19:08:28 INFO - PID 4390 | Values: 113.0 113.0 114.0 112.0 115.0 111.0 117.0 111.0 116.0 123.0 19:08:28 INFO - PID 4390 | 19:08:28 INFO - PID 4390 | [#2] audio-dft Cycles:10 Average:162.70 Median:163.00 stddev:1.06 (0.6%) stddev-sans-first:1.12 19:08:28 INFO - PID 4390 | Values: 163.0 165.0 163.0 163.0 163.0 163.0 161.0 162.0 162.0 162.0 19:08:28 INFO - PID 4390 | 19:08:28 INFO - PID 4390 | [#3] audio-fft Cycles:10 Average:73.70 Median:73.00 stddev:1.57 (2.1%) stddev-sans-first:1.66 19:08:28 INFO - PID 4390 | Values: 74.0 73.0 77.0 73.0 73.0 76.0 73.0 72.0 73.0 73.0 19:08:28 INFO - PID 4390 | 19:08:28 INFO - PID 4390 | [#4] audio-oscillator Cycles:10 Average:78.50 Median:78.00 stddev:2.01 (2.6%) stddev-sans-first:2.06 19:08:28 INFO - PID 4390 | Values: 77.0 77.0 77.0 77.0 77.0 81.0 81.0 82.0 78.0 78.0 19:08:28 INFO - PID 4390 | 19:08:28 INFO - PID 4390 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:0.79 (0.7%) stddev-sans-first:0.71 19:08:28 INFO - PID 4390 | Values: 115.0 114.0 113.0 113.0 114.0 113.0 114.0 113.0 114.0 115.0 19:08:28 INFO - PID 4390 | 19:08:28 INFO - PID 4390 | [#6] imaging-darkroom Cycles:10 Average:150.00 Median:150.00 stddev:1.41 (0.9%) stddev-sans-first:1.45 19:08:28 INFO - PID 4390 | Values: 149.0 153.0 150.0 152.0 150.0 149.0 149.0 149.0 149.0 150.0 19:08:28 INFO - PID 4390 | 19:08:28 INFO - PID 4390 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:1.75 (1.9%) stddev-sans-first:1.86 19:08:28 INFO - PID 4390 | Values: 94.0 94.0 94.0 99.0 93.0 93.0 94.0 93.0 94.0 94.0 19:08:28 INFO - PID 4390 | 19:08:28 INFO - PID 4390 | [#8] json-parse-financial Cycles:10 Average:46.60 Median:47.50 stddev:2.07 (4.3%) stddev-sans-first:2.11 19:08:28 INFO - PID 4390 | Values: 45.0 44.0 47.0 48.0 50.0 49.0 45.0 45.0 48.0 45.0 19:08:28 INFO - PID 4390 | 19:08:28 INFO - PID 4390 | [#9] json-stringify-tinderbox Cycles:10 Average:37.40 Median:37.50 stddev:0.52 (1.4%) stddev-sans-first:0.53 19:08:28 INFO - PID 4390 | Values: 37.0 38.0 37.0 38.0 37.0 38.0 37.0 38.0 37.0 37.0 19:08:28 INFO - PID 4390 | 19:08:28 INFO - PID 4390 | [#10] stanford-crypto-aes Cycles:10 Average:70.80 Median:71.50 stddev:1.87 (2.6%) stddev-sans-first:1.99 19:08:28 INFO - PID 4390 | Values: 71.0 72.0 73.0 70.0 74.0 69.0 69.0 72.0 69.0 69.0 19:08:28 INFO - PID 4390 | 19:08:28 INFO - PID 4390 | [#11] stanford-crypto-ccm Cycles:10 Average:107.40 Median:109.50 stddev:4.62 (4.2%) stddev-sans-first:4.74 19:08:28 INFO - PID 4390 | Values: 104.0 100.0 103.0 110.0 109.0 104.0 112.0 115.0 110.0 107.0 19:08:28 INFO - PID 4390 | 19:08:28 INFO - PID 4390 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.00 Median:151.50 stddev:3.94 (2.6%) stddev-sans-first:2.93 19:08:28 INFO - PID 4390 | Values: 160.0 152.0 149.0 151.0 152.0 158.0 148.0 151.0 150.0 149.0 19:08:28 INFO - PID 4390 | 19:08:28 INFO - PID 4390 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.70 Median:60.00 stddev:3.16 (5.3%) stddev-sans-first:3.32 19:08:28 INFO - PID 4390 | Values: 62.0 60.0 58.0 60.0 58.0 60.0 69.0 61.0 59.0 60.0 19:08:28 INFO - PID 4390 | -------- Summary: end -------- 19:08:28 INFO - PID 4390 | 19:08:28 INFO - PID 4390 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 19:08:29 INFO - TEST-INFO | 4390: exit 0 19:08:29 INFO - TEST-OK | kraken | took 58195ms 19:08:29 INFO - SUITE-END | took 1164s 19:08:29 INFO - Completed test suite (00:19:24) 19:08:29 INFO - javascript benchmark 19:08:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s", "stylo_disabled"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9120.835530541706, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3867.709921755868, 3887.416887466878, 3898.883033508994, 3911.745736527125, 3913.2760439318745, 6315.526335524843, 6354.459870910978, 6369.172007639007, 6392.6803659817, 6462.450691691212, 6389.265234498034, 6394.072859435379, 6448.387403196689, 6467.838304042401, 6490.123833282505, 5704.515116215106, 5801.390853960336, 5834.912476312858, 5842.269716285465, 5853.268341457318, 95.1893168105333, 5477.424870388611, 5699.373068962432, 5802.042662960606, 5807.332156801979, 5705.343885453164, 5718.1136923776885, 5803.245578074865, 5806.506446951993, 5815.767369305244, 5696.943030569682, 5725.427457254241, 5780.71096445176, 5782.884342313172, 5792.420757924174, 5715.857103572403, 5717.256756621654, 5790.160426738142, 5794.188813566104, 5814.709264536757, 6157.7514787032, 6315.873682526324, 6377.808665740035, 6398.744050237985, 6441.645709485976, 6810.591364518095, 6815.386615204656, 6925.995730619032, 6929.064576282188, 7008.754693585751, 6841.760538381182, 6851.45188384928, 6852.246252912176, 6853.348931851466, 6891.483138764613, 6493.285738568754, 6507.739690412378, 6510.251321098087, 6511.38141876521, 6527.2167339919515, 7082.92917070833, 7169.533980291264, 7176.318249766264, 7179.1026121734785, 7188.784336469915, 6804.863902721918, 6808.148981377328, 6842.0, 6900.309969003059, 6924.376806087476, 6830.726770929206, 6885.2770459101075, 6891.104156459728, 6895.55178913369, 7036.6481675916, 6840.144981877265, 6849.280825513349, 6862.965685171542, 6867.931320686829, 6915.4121899638085, 6463.741450342028, 6815.556988795713, 6902.792916212522, 6905.309469053054, 6981.232064472904, 4954.777035033432, 4961.925571116436, 4967.90064198714, 5016.673916195508, 5021.799128034838, 4456.3538286948215, 4496.213162696515, 4507.797149128297, 4515.841945531891, 4537.228671125917, 6351.142595749607, 6383.872322553616, 6386.201724784402, 6401.9039714404325, 6406.615603063831, 6799.660016999131, 6876.865317222602, 6968.547044442095, 6972.6862291196, 6985.301469852974, 6339.0808332791485, 6345.809625711236, 6416.03759436079, 6427.903581446282, 6434.64609446485, 5407.891842163135, 5509.421510741315, 5522.0, 5540.750666220029, 5565.359983601857, 6995.615241161683, 7100.9289907100265, 7128.786136415916, 7151.106111736033, 7173.390261827699, 6674.899876501953, 6745.460363170934, 6760.290169532227, 6814.4548436124, 6880.827979300558, 7200.675969581277, 7264.34620884123, 7274.345308922222, 7285.7449989252245, 7290.854182916418, 6870.072540207008, 6881.071055407656, 6937.445004399737, 6960.060391847238, 6969.407600354027, 6795.082663840319, 6908.0, 6957.478189135733, 6959.96520017417, 7069.787906362817, 6640.302768209462, 6694.631795251308, 6710.16122984627, 6715.1941766988275, 6715.1941766988275, 6616.371444712745, 6721.966390168018, 6750.594964302158, 6765.661716914233, 6771.729130834711, 6589.604623722592, 6590.143281373391, 6616.867662646817, 6659.933400665931, 6689.665516724047, 6596.373344532261, 6753.696083676345, 6770.661466926733, 6852.588844669336, 6871.0, 6444.3233460487845, 6512.120863683532, 6535.901961470677, 6552.082708420729, 6554.410103090745], "value": 6261.368712014977, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [966.1546147121269, 995.7162208770502, 1001.6043662753208, 1003.3779056984671, 1005.4771518810214, 41845.95385115374, 46428.0, 46459.76770116149, 46591.76704116475, 46668.0, 43642.12715745683, 43770.0, 44027.77986110065, 44030.779846100726, 44382.0, 7761.611919404036, 7803.375729941619, 7817.0, 7849.6075196240245, 7889.211078892094, 7730.729424470144, 7771.533707977511, 7793.298603125718, 7798.337141342992, 7807.102183248942, 7766.3786897048385, 7786.8442631147345, 7788.0654321481215, 8316.958415207946, 8590.484570925733, 8518.40371174018, 8568.443051201713, 8580.056193818706, 8583.699570515064, 8583.785405364853, 8194.918050819475, 8393.916060839374, 8393.95075615548, 8474.915250847474, 8480.067192608809, 41924.58075419237, 42439.78780106111, 42484.0, 42492.57507424917, 42545.36181957273, 3688.6311368863167, 3709.7403181777286, 3716.1824398632257, 3722.032271609401, 3724.348239058181, 34633.30733385318, 34768.0, 34790.304193916236, 34846.0, 34854.65145348565, 5381.623286369956, 5408.729563521848, 5415.566754659618, 5433.510984011418, 5451.209574611698, 210414.0, 210584.0, 211280.0, 211584.94207529016, 212448.0, 47206.76396617995, 47897.52102479, 48178.75910620424, 52407.47592524102, 52424.737876311134, 55873.0, 56310.339827911055, 56399.0, 57057.14414283789, 57106.428935710945, 16555.50333489997, 16733.665326693397, 16873.156342182843, 16911.91544042272, 16924.661506769797, 13662.726745465234, 13695.726085478234, 13700.93149534246, 13711.314434278247, 13728.313584320744, 14877.851221487646, 14885.288191857859, 14966.625834354229, 14970.0, 15172.544823655307, 5808.5933984621515, 5817.447342502455, 5849.239598852122, 5901.3213480450295, 5982.521398288127, 1068.428390810928, 1112.3193104617833, 1114.2033446085925, 1121.7980763462492, 1127.610974213895, 25715.35711607225, 26079.348016299744, 26090.478190436086, 26105.608415873776, 26422.867885660835, 1209.214010892909, 1219.2440686774257, 1222.2910711787142, 1222.3643705273207, 1226.834377359045, 13514.729705406033, 13552.186868787905, 13763.655908602364, 13784.517541885985, 14005.229712365917, 274486.0, 275996.0, 276010.0, 276238.0, 276410.0, 59790.40209597848, 59900.0, 60017.39982600205, 60124.0, 60165.0, 1522.7944227529363, 1532.5402379286393, 1546.891717579781, 1561.1959840682057, 1562.2735428025794, 25048.874755626104, 25198.370040749127, 25548.0, 25653.0, 25775.6133657999, 41794.79102604467, 41858.58141418547, 41871.0, 41894.7905260484, 41914.58085419107, 166978.0, 166999.0, 167778.16110919366, 168328.0, 168836.0, 34298.0, 35405.114872128404, 37141.071473213386, 37199.035114442864, 37335.6266437343, 49573.25640115329, 49611.0, 49873.75063124806, 50312.0, 50645.746771265905, 35388.11529711778, 35409.0, 35613.0, 36252.09369765777, 36562.63437365699, 35104.648953510135, 35379.292414152085, 35382.646173537934, 35924.28151437009, 35929.64070359263, 41513.0, 41893.1621367577, 42048.789756051025, 42059.579404206794, 42185.57814421816, 34035.65964340325, 34145.48781768226, 34236.14409639779, 34552.48171277483, 34795.82602087074, 25812.22563323103, 25924.35189120287, 26320.473590528465, 26323.605145922444, 26758.464830703666], "value": 18579.304733159057, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [613.5459759777766, 621.5711159300082, 623.2707731953615, 624.2321944008869, 630.3727790848104, 23124.65313020306, 23461.41346466339, 23939.64090538639, 23964.0, 24023.879880600594, 23471.765282347173, 23932.641010384814, 24156.63765043526, 24214.15250466234, 24254.0, 6718.160229971254, 6899.793006209822, 6905.896411553806, 6912.239653638096, 6914.239433662293, 6398.424141827235, 6639.5684280521855, 6690.431313338372, 6711.094002309675, 6841.760538381158, 6653.500987425939, 6655.500837437212, 6663.666816659172, 6665.266820649725, 6666.866662666744, 6383.808485745435, 6637.303083176245, 6656.600603963753, 6658.833529161761, 6659.3340665933265, 6265.342139075423, 6268.059791031336, 6271.404216599416, 6271.780487682955, 6271.937280627181, 30280.697193028227, 30825.84587077073, 30920.226994325323, 30941.0, 30965.845170774002, 2234.5866014787316, 2260.107257633242, 2261.298997310839, 2261.3329067925, 2263.1626298269744, 20911.790882091136, 21348.78651213499, 21378.572428551342, 21415.250466233763, 21476.140954361807, 2488.25352394281, 2544.211294498711, 2575.8969641214517, 2591.054265193191, 2606.5568853294985, 141292.0, 147071.0, 148763.0, 154400.0, 155262.22368888196, 15848.92075539615, 16148.27332770028, 16539.0, 17103.572410689583, 17109.401170958958, 26654.0, 26690.466190676078, 26690.866545667537, 26725.732742672324, 26753.598696019577, 10222.575437228612, 10331.22515811302, 10455.0, 10499.527521261562, 10525.21060920434, 9131.81736365269, 9205.125513076247, 9267.582958766738, 9356.578953947088, 9400.059994000681, 9801.019898010141, 9805.55874985613, 9874.654387096418, 9974.351667141613, 10178.541965611565, 2799.860006999642, 2829.2078218098964, 2849.4016256585933, 2904.8838046477904, 3007.5488676698233, 1045.8692663417073, 1049.821530339844, 1062.5802807890839, 1085.2511766880828, 1087.8803331633515, 21746.238881639067, 22139.667904981437, 22329.42246839394, 22367.888160559094, 22572.322830315115, 1100.779844031197, 1124.960626378073, 1125.1673761416494, 1127.4926283172606, 1142.4344949250174, 11788.646340609796, 12116.454759535842, 12137.696557585954, 12218.694532636577, 12224.388780560936, 552338.0, 555941.0, 568340.0, 568952.0, 577110.0, 122317.0, 124730.0, 127330.0, 127819.0, 128561.0, 1226.466487078124, 1243.1173866554848, 1265.2598230035533, 1268.315109840694, 1271.0975207602705, 19706.704399433733, 19819.702704459734, 19974.90012549928, 20094.095765690286, 20207.69688454645, 59586.0, 60582.39417605768, 60785.0, 62568.37431625625, 63294.36705632885, 79305.0, 79313.2068679329, 79624.20375796167, 80897.0, 81678.0, 15811.367545298059, 16133.435329763402, 16174.353025879303, 16269.51191464258, 16297.837021629632, 24735.50528989446, 24858.0, 24923.0, 24955.376115597257, 25057.498850022534, 19207.423777286705, 20140.697889531963, 20187.79812201859, 20233.0, 20503.794962050786, 17870.821291786917, 17899.37352192667, 18221.45335639933, 18269.0, 18541.35105271309, 20109.19563217455, 20453.795462045786, 20454.18183272712, 20508.179672813516, 20580.794192058485, 15818.367265309258, 15917.761233581274, 16020.759688604914, 16068.517944461684, 16114.919425402797, 19795.505112372306, 20000.799991999895, 20036.59926801485, 20058.39824805258, 20288.0], "value": 12852.180019272579, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1385.8336999560054, 1473.27809373407, 1499.3777582303344, 1504.217806740495, 1512.0625212368334, 16754.413595524144, 17437.0, 17446.563835904108, 17459.476215713516, 17464.214110365047, 16798.412055578057, 16851.66296674069, 16868.156592170373, 16927.661446771057, 16961.236744346505, 2756.2558109310476, 2763.2539214412095, 2835.7306055924655, 2842.4173044525824, 2845.6727476340175, 2779.7359250871136, 2828.0667379764627, 2839.8154119982237, 2853.5862299966493, 2858.6992918222263, 2752.174347695693, 2832.4335132973383, 2838.5458326667735, 2840.4745122152467, 2840.8011439199263, 2792.8603569821425, 2805.382815780535, 2811.732885375886, 2818.5208514552573, 2822.47784159929, 2601.349662584354, 2694.420699549606, 2694.757471827545, 2695.9865200674067, 2705.337192387858, 16939.661206775796, 17078.23147958345, 17080.0, 17092.829071709373, 17105.14474276281, 8303.626336814857, 8634.093420190853, 8641.179087986631, 8646.783830404227, 8648.913510864873, 5366.356037275513, 5451.100568406208, 5563.833085007456, 5622.297212848394, 5655.293088363955, 2071.4200023993308, 2093.95812083759, 2098.244631932503, 2105.95788084239, 2130.062772380163, 30444.0, 30591.08226753201, 30630.0, 30635.846820765753, 30683.386332273454, 7090.680919358538, 7193.784186474414, 7255.347018768336, 7307.853842923112, 7357.632118394059, 19943.401697949084, 19973.500662483264, 19975.900120499304, 20032.799672003384, 20040.0, 7890.210978902064, 8110.107888132301, 8137.389695772841, 8150.307223885918, 8153.714619988391, 7279.781606551812, 7378.077740282465, 7378.225286344964, 7408.629568521553, 7506.28690274423, 5340.706261155596, 5548.742185396826, 5570.41510641385, 5582.134769110794, 5611.971940140274, 1501.7597184450435, 1517.9165145917082, 1518.5975716435153, 1532.9616759580877, 1556.4163438710484, 958.5973890965811, 965.7541771115323, 975.2990585378408, 992.161623428202, 998.3959704378747, 8230.300424463869, 8260.586970651444, 8304.792380190424, 8305.211004954519, 8337.082920878698, 796.8725003999332, 800.4596897094438, 816.7713040348712, 819.2135549872071, 826.1490664615455, 5440.510354068153, 6050.606710563888, 6140.5701600887505, 6267.1539342189135, 6292.4966002719675, 22945.77054229436, 24336.391590210387, 24971.62542561863, 25514.489710206064, 25572.616410753853, 24224.87887560551, 24864.751352486244, 24871.875640622042, 25319.240422787345, 25398.0, 12147.453364598614, 12232.816507752214, 12486.687832804253, 12562.937185314198, 12565.246085234916, 9171.44971301724, 9338.626454941998, 9364.765880853032, 9399.530023498934, 9434.764130896783, 20739.170433182502, 20847.270345537832, 21097.683534746684, 21255.893720531298, 21619.35141945744, 21837.672434913173, 21967.23114690978, 22092.779072209716, 22117.77882221157, 22190.778092219516, 12451.937740311241, 12726.363681816058, 12743.49026038948, 12758.0, 12763.298018609066, 15645.530634080997, 15737.134457604941, 15739.527814165593, 15987.760183597024, 16000.599985000468, 12568.30874301922, 12749.107562470537, 12804.167729097579, 13074.738505229652, 13301.068925175145, 12367.0, 12446.128770985944, 12449.37753112359, 12795.296258705488, 12812.935935320636, 10874.945625271823, 10894.78210435771, 10917.508712107816, 11143.16426268017, 11146.0, 8073.959630201812, 8160.306373958043, 8160.673573057249, 8233.588320583827, 8272.545010024507, 8619.91380086191, 8731.65073397057, 8736.213740763362, 8800.251978581893, 8842.557872106498], "value": 7249.529543987289, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [543.9347278326601, 565.2228186243915, 571.8055861007257, 575.1057106199863, 594.129600135801, 6300.558960872735, 6381.0, 6463.511548053998, 6471.644059576722, 6483.675816209195, 6174.506039516839, 6386.201724784402, 6386.936130638692, 6406.327335629761, 6431.549791514596, 5217.556507696831, 5222.216667499886, 5222.32109825724, 5240.528352448279, 5240.842774716764, 4967.801287948478, 5126.92309615356, 5212.556932660728, 5220.530152286294, 5251.687078230442, 5079.669821461612, 5081.263216833557, 5096.108181068316, 5101.183810590306, 5108.514691104339, 5040.420351659551, 5041.294218809369, 5067.265246539231, 5078.085944530001, 5087.287779710843, 4595.839145629887, 4845.345878306419, 4925.916298414343, 4992.650514463989, 5001.724905130216, 6349.8412539686415, 6353.523485738542, 6359.491240700733, 6366.87266254677, 6380.61716297019, 6278.937210627881, 6288.654124023177, 6307.9684601577155, 6345.9365406345805, 6363.268224154189, 6936.861262774767, 6939.356837660218, 6942.236354001056, 6953.478489113337, 6965.686544105477, 5623.397331760429, 5639.943600563983, 5640.597921359245, 5645.971770141123, 5661.60368774186, 6935.826604334881, 6973.686184121676, 7044.859102817915, 7108.218096009435, 7128.180259270147, 5770.538356931435, 5801.767929282782, 5816.41835816415, 5826.989688557441, 5844.444777746107, 5909.349971503131, 5920.0, 5923.526117910643, 5938.28740551128, 5945.989181839098, 6246.562740608114, 6277.843053923689, 6281.748730050746, 6296.7721294348075, 6313.715882785285, 6866.931330686629, 6877.65611719412, 6886.724531018703, 6889.311068893071, 6901.482388820858, 2045.703373010935, 2069.7102405663218, 2078.251829341436, 2083.864548804323, 2090.6863970404256, 1495.3719437836135, 1557.909463375619, 1559.3528685595352, 1568.8352722964153, 1577.7869987551762, 6232.657203853832, 6281.654509002049, 6281.654509002049, 6282.560220784501, 6343.27052388972, 6884.965575172092, 6885.827854303582, 6891.517593768388, 6910.997905303803, 6914.792556223321, 6238.313785483593, 6295.401936815995, 6312.5581209314905, 6316.30520642729, 6385.872282554323, 3611.2777444511203, 3618.819059047037, 3628.818559072036, 3638.69071128957, 3662.8901132966053, 7007.544509606962, 7087.397571206505, 7107.715691372287, 7116.359527642434, 7182.60495672743, 7042.753503627348, 7058.447141628833, 7074.398676112485, 7080.2919708028785, 7187.31720486553, 7163.570185788765, 7183.389411899942, 7196.748113815991, 7206.207317195104, 7219.747308844376, 6878.759243426456, 6882.518223724492, 6930.930690693029, 6939.236683964861, 6953.721851125898, 6863.485238607224, 6884.380405763505, 6916.371778724053, 6930.0, 6942.340477654615, 7150.141982962078, 7210.315020073084, 7224.638768061471, 7233.493655444279, 7247.565146091251, 5801.738921748616, 5802.912956305574, 5823.708814559171, 5851.795187168429, 5874.118882167709, 6744.763933262312, 6767.39093481589, 6986.043907705851, 6989.930100698928, 7063.117110361205, 6609.834754131185, 6613.537052406477, 6640.945165223858, 6692.230393504809, 6724.596524208563, 6582.539222254396, 6611.834704132435, 6612.437942774918, 6672.466202703869, 6693.497987650849, 6423.29343772194, 6539.716485701872, 6571.901421478586, 6609.339066093352, 6613.924525095661, 6524.543281970216, 6550.689862027709, 6597.505187111082, 6693.564918280296, 6734.730610775514, 6375.0, 6523.804285871432, 6525.738970441129, 6548.1814773153355, 6563.573367731082], "value": 5417.618589863877, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [970.9271804614656, 988.4563490080455, 999.4003597841296, 1005.9245556583263, 1023.2019025160375, 19320.613587728236, 19644.0, 19672.60654786907, 19673.31143409981, 19723.309684161057, 18474.630507389844, 18556.0, 18666.06669666518, 19592.706109408333, 19767.209311627517, 1032.8244198486257, 1060.5757696921216, 1060.6870973062964, 1061.2147011211725, 1061.5488417422603, 1062.1927335225207, 1081.8809930907632, 1083.030687534656, 1093.907017903479, 1109.7724966381913, 1166.8133098704209, 1171.0690001448868, 1171.1743221029196, 1171.226990186477, 1171.3850228629994, 1150.5742875136211, 1155.5262342439644, 1162.697698598362, 1164.5865717670274, 1168.0, 851.638053827121, 852.910444403335, 856.8029353248726, 857.3869683176558, 858.3176374782063, 20040.298589549442, 20725.481862953395, 21555.1377944882, 21884.01521931501, 21923.890380547997, 22963.885180574158, 23186.0, 23626.0, 23636.054557817668, 24186.516269674685, 17019.149042547822, 17371.30514779407, 17420.30318787247, 17529.912350438295, 17636.38272660451, 3983.322835118037, 4059.0, 4059.634632883025, 4063.3904914262803, 4064.1465292288653, 137962.0, 147028.0, 147514.99999999892, 149725.0, 160861.0, 17879.28482860697, 17938.461846144637, 18385.81614183841, 18392.172352244183, 18454.0, 33942.830285848904, 33970.49044264338, 34081.0, 35298.64701352954, 35587.4661880072, 9397.812043759086, 9550.522473876417, 9560.952195239119, 9562.043795620382, 9579.521023948775, 4390.626796722315, 4455.86632401022, 4458.9331160032625, 4549.749763763025, 4556.11155824611, 5865.560082993792, 6100.3899610039525, 6101.084837274444, 6101.786437474667, 6122.969385153046, 1224.4184012593946, 1249.056961993689, 1262.1480500662017, 1265.531753251303, 1272.63093702827, 673.6699017481399, 684.4626967830229, 689.19020151323, 696.1993707236718, 699.9160100787836, 12143.757124857453, 13011.804822927663, 13418.0, 14031.859681403259, 14118.0, 726.1757904778009, 740.637087826961, 748.5059860492158, 750.9286617771303, 754.6943487887414, 5860.2381690380835, 5882.382349853204, 5920.526357891358, 6096.908546371808, 6098.603590766586, 144892.27553862164, 147472.0, 147505.0, 149128.0, 150046.24976875263, 127226.36386818007, 128475.07944888767, 128620.0, 130289.0, 133809.33095334462, 107208.0, 110760.0, 113793.0, 114065.42967285277, 114766.42616787029, 11147.557352904725, 11161.0, 11180.23595724575, 11310.94344528272, 11492.195546312012, 55051.0, 55228.0, 55787.44212557985, 55914.0, 56147.71926140343, 71824.28175718385, 72357.0, 72561.6371918137, 72654.27345726475, 72682.63658681673, 14963.551093467213, 15681.607959801097, 15720.528384148494, 15832.208389580705, 15919.044857308598, 18806.52983675419, 19235.90382048137, 19322.516937076685, 19336.70994935049, 19436.90281548583, 16700.665986679956, 16750.66498670044, 16761.1619419031, 16832.07423591665, 17107.315707371567, 17427.128643568023, 17708.0, 17721.556961076076, 17826.46520604384, 18014.5496362592, 17042.829571704126, 17110.315587376364, 17113.657726845642, 17135.914320428816, 17246.741298880777, 11809.0, 11878.643640690447, 11925.10561707858, 11928.224665396721, 12380.133390662928, 12397.380130993595, 12460.750784984068, 12538.623841284776, 12663.0, 12670.556530521388], "value": 9803.671761312327, "unit": "score"}]}, {"extraOptions": ["e10s", "stylo_disabled"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1368.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 106.0, 105.0, 105.0, 105.0, 107.0, 106.0, 106.0, 105.0, 106.0], "value": 105.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 112.0, 115.0, 111.0, 117.0, 111.0, 116.0, 123.0], "value": 114.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 163.0, 163.0, 163.0, 163.0, 161.0, 162.0, 162.0, 162.0], "value": 162.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 77.0, 73.0, 73.0, 76.0, 73.0, 72.0, 73.0, 73.0], "value": 73.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 77.0, 77.0, 77.0, 81.0, 81.0, 82.0, 78.0, 78.0], "value": 78.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 113.0, 113.0, 114.0, 113.0, 114.0, 113.0, 114.0, 115.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 153.0, 150.0, 152.0, 150.0, 149.0, 149.0, 149.0, 149.0, 150.0], "value": 150.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 99.0, 93.0, 93.0, 94.0, 93.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 44.0, 47.0, 48.0, 50.0, 49.0, 45.0, 45.0, 48.0, 45.0], "value": 46.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 38.0, 37.0, 38.0, 37.0, 38.0, 37.0, 38.0, 37.0, 37.0], "value": 37.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 73.0, 70.0, 74.0, 69.0, 69.0, 72.0, 69.0, 69.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 100.0, 103.0, 110.0, 109.0, 104.0, 112.0, 115.0, 110.0, 107.0], "value": 107.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 152.0, 149.0, 151.0, 152.0, 158.0, 148.0, 151.0, 150.0, 149.0], "value": 152.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 58.0, 60.0, 58.0, 60.0, 69.0, 61.0, 59.0, 60.0], "value": 60.7, "unit": "score"}]}]} 19:08:29 INFO - Return code: 0 19:08:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:08:29 INFO - # TBPL SUCCESS # 19:08:29 INFO - Running post-action listener: _package_coverage_data 19:08:29 INFO - Running post-action listener: _resource_record_post_action 19:08:29 INFO - [mozharness: 2017-09-21 02:08:29.243378Z] Finished run-tests step (success) 19:08:29 INFO - Running post-run listener: _resource_record_post_run 19:08:29 INFO - Total resource usage - Wall time: 1183s; CPU: 13.0%; Read bytes: 0; Write bytes: 319365120; Read time: 0; Write time: 214096 19:08:29 INFO - TinderboxPrint: CPU usage
13.1% 19:08:29 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 19:08:29 INFO - TinderboxPrint: I/O write bytes / time
319,365,120 / 214,096 19:08:29 INFO - TinderboxPrint: CPU idle
8,199.1 (86.9%) 19:08:29 INFO - TinderboxPrint: CPU user
1,185.2 (12.6%) 19:08:29 INFO - TinderboxPrint: Swap in / out
0 / 0 19:08:29 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10354688; Read time: 0; Write time: 656 19:08:29 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:08:29 INFO - run-tests - Wall time: 1165s; CPU: 13.0%; Read bytes: 0; Write bytes: 308125696; Read time: 0; Write time: 213340 19:08:29 INFO - Running post-run listener: _upload_blobber_files 19:08:29 INFO - Blob upload gear active. 19:08:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:08:29 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 19:08:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:08:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:08:30 INFO - (blobuploader) - INFO - Open directory for files ... 19:08:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:08:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:08:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:08:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:08:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:08:31 INFO - (blobuploader) - INFO - Done attempting. 19:08:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:08:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:08:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:08:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:08:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:08:38 INFO - (blobuploader) - INFO - Done attempting. 19:08:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log ... 19:08:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:08:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:08:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-stylo-disabled-e10s_errorsummary.log: uploaded 19:08:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:08:39 INFO - (blobuploader) - INFO - Done attempting. 19:08:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log ... 19:08:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:08:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:08:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-stylo-disabled-e10s_raw.log: uploaded 19:08:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:08:40 INFO - (blobuploader) - INFO - Done attempting. 19:08:40 INFO - (blobuploader) - INFO - Iteration through files over. 19:08:40 INFO - Return code: 0 19:08:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:08:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:08:40 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e1b4509e37bdad224a32ba5495d2f89158cbff2f057b7151c68e17d20ebce1786b78b6b2163b11e5e730a050d2831106a7fa4ee06634e800961fed80cbbdcd9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/309464754837d66f7aca9f9ac739bf10cf3a96aa7d0ca993de7d597f7bf119ca1097da2059c464d3c2bebed283ba915e4c36f9bc8139f7f242d68071346d5269", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2432ad87206a7b6761e8f5747e9259a50ee1bbdce93ca1467bf3674c0f5397cd8123e3fd6857bb1c7136b3ff755584499a33bff4ebf7de2ab159b49957b9148", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acf26e18d1596716b8c43d1e717ef2961317fdfad55c69e1fed88d11321b9a857bdb87ede283b0bda4938ba31e19cf69bfebb31c259ce0988d251d944038ce57"} 19:08:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:08:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:08:40 INFO - Contents: 19:08:40 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e1b4509e37bdad224a32ba5495d2f89158cbff2f057b7151c68e17d20ebce1786b78b6b2163b11e5e730a050d2831106a7fa4ee06634e800961fed80cbbdcd9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/309464754837d66f7aca9f9ac739bf10cf3a96aa7d0ca993de7d597f7bf119ca1097da2059c464d3c2bebed283ba915e4c36f9bc8139f7f242d68071346d5269", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2432ad87206a7b6761e8f5747e9259a50ee1bbdce93ca1467bf3674c0f5397cd8123e3fd6857bb1c7136b3ff755584499a33bff4ebf7de2ab159b49957b9148", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acf26e18d1596716b8c43d1e717ef2961317fdfad55c69e1fed88d11321b9a857bdb87ede283b0bda4938ba31e19cf69bfebb31c259ce0988d251d944038ce57"} 19:08:40 INFO - Running post-run listener: copy_logs_to_upload_dir 19:08:40 INFO - Copying logs to upload dir... 19:08:40 INFO - mkdir: /builds/slave/test/build/upload/logs 19:08:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.379172 ========= master_lag: 0.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2017-09-20 19:08:40.649042) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-20 19:08:40.656222) ========= 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-1505958484.852817-104608235 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/sha512/0e1b4509e37bdad224a32ba5495d2f89158cbff2f057b7151c68e17d20ebce1786b78b6b2163b11e5e730a050d2831106a7fa4ee06634e800961fed80cbbdcd9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/309464754837d66f7aca9f9ac739bf10cf3a96aa7d0ca993de7d597f7bf119ca1097da2059c464d3c2bebed283ba915e4c36f9bc8139f7f242d68071346d5269", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2432ad87206a7b6761e8f5747e9259a50ee1bbdce93ca1467bf3674c0f5397cd8123e3fd6857bb1c7136b3ff755584499a33bff4ebf7de2ab159b49957b9148", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acf26e18d1596716b8c43d1e717ef2961317fdfad55c69e1fed88d11321b9a857bdb87ede283b0bda4938ba31e19cf69bfebb31c259ce0988d251d944038ce57"} build_url:https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011590 build_url: 'https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e1b4509e37bdad224a32ba5495d2f89158cbff2f057b7151c68e17d20ebce1786b78b6b2163b11e5e730a050d2831106a7fa4ee06634e800961fed80cbbdcd9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/309464754837d66f7aca9f9ac739bf10cf3a96aa7d0ca993de7d597f7bf119ca1097da2059c464d3c2bebed283ba915e4c36f9bc8139f7f242d68071346d5269", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2432ad87206a7b6761e8f5747e9259a50ee1bbdce93ca1467bf3674c0f5397cd8123e3fd6857bb1c7136b3ff755584499a33bff4ebf7de2ab159b49957b9148", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acf26e18d1596716b8c43d1e717ef2961317fdfad55c69e1fed88d11321b9a857bdb87ede283b0bda4938ba31e19cf69bfebb31c259ce0988d251d944038ce57"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-20 19:08:40.687773) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 19:08:40.688133) ========= 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-1505958484.852817-104608235 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004638 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 19:08:40.772805) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-20 19:08:40.773182) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-20 19:08:40.773582) ========= ========= Total master_lag: 0.28 =========