builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-071 starttime: 1503661912.66 results: success (0) revision: 3bbd8e25df3d92464b97bcb42413febd9041af6f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:52.657246) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:52.657922) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:52.658193) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:52.716425) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:52.716816) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-25 04:51:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 83.4M=0s 2017-08-25 04:51:53 (83.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126170 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:52.858399) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:52.861753) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019969 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:52.911283) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-25 04:51:52.914639) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3bbd8e25df3d92464b97bcb42413febd9041af6f --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 3bbd8e25df3d92464b97bcb42413febd9041af6f --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=dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-25 04:51:53,286 truncating revision to first 12 chars 2017-08-25 04:51:53,286 Setting DEBUG logging. 2017-08-25 04:51:53,286 attempt 1/10 2017-08-25 04:51:53,286 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3bbd8e25df3d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-25 04:51:53,979 unpacking tar archive at: mozilla-central-3bbd8e25df3d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.026195 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-25 04:51:53.955400) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:53.958739) ========= script_repo_revision: 3bbd8e25df3d92464b97bcb42413febd9041af6f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:53.959124) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:53.959421) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:53.970947) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-08-25 04:51:53.971260) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-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-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=dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:51:54 INFO - MultiFileLogger online at 20170825 04:51:54 in /builds/slave/test 04:51:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:51:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:51:54 INFO - {'append_to_log': False, 04:51:54 INFO - 'base_work_dir': '/builds/slave/test', 04:51:54 INFO - 'blob_upload_branch': 'Firefox', 04:51:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:51:54 INFO - 'branch': 'Firefox', 04:51:54 INFO - 'buildbot_json_path': 'buildprops.json', 04:51:54 INFO - 'code_coverage': False, 04:51:54 INFO - 'config_files': ('talos/linux_config.py',), 04:51:54 INFO - 'default_actions': ('clobber', 04:51:54 INFO - 'read-buildbot-config', 04:51:54 INFO - 'download-and-extract', 04:51:54 INFO - 'populate-webroot', 04:51:54 INFO - 'create-virtualenv', 04:51:54 INFO - 'install', 04:51:54 INFO - 'setup-mitmproxy', 04:51:54 INFO - 'run-tests'), 04:51:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:51:54 INFO - 'disable_ccov_upload': False, 04:51:54 INFO - 'download_minidump_stackwalk': True, 04:51:54 INFO - 'download_symbols': 'ondemand', 04:51:54 INFO - 'enable_stylo': False, 04:51:54 INFO - 'enable_webrender': False, 04:51:54 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 04:51:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:51:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:51:54 INFO - 'gecko_profile': False, 04:51:54 INFO - 'gecko_profile_interval': 0, 04:51:54 INFO - 'installer_path': 'installer.exe', 04:51:54 INFO - 'log_level': 'info', 04:51:54 INFO - 'log_name': 'talos', 04:51:54 INFO - 'log_to_console': True, 04:51:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:51:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:51:54 INFO - 'opt_config_files': (), 04:51:54 INFO - 'pip_index': False, 04:51:54 INFO - 'suite': 'dromaeojs-e10s', 04:51:54 INFO - 'system_bits': '32', 04:51:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:51:54 INFO - 'title': 'talos-linux64-ix-071', 04:51:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:51:54 INFO - 'use_talos_json': True, 04:51:54 INFO - 'verify': 'False', 04:51:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:51:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:51:54 INFO - 'work_dir': 'build'} 04:51:54 INFO - [mozharness: 2017-08-25 11:51:54.489943Z] Running clobber step. 04:51:54 INFO - Running pre-action listener: _resource_record_pre_action 04:51:54 INFO - Running main action method: clobber 04:51:54 INFO - rmtree: /builds/slave/test/build 04:51:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:51:55 INFO - Running post-action listener: _resource_record_post_action 04:51:55 INFO - [mozharness: 2017-08-25 11:51:55.510748Z] Finished clobber step (success) 04:51:55 INFO - [mozharness: 2017-08-25 11:51:55.510845Z] Running read-buildbot-config step. 04:51:55 INFO - Running pre-action listener: _resource_record_pre_action 04:51:55 INFO - Running main action method: read_buildbot_config 04:51:55 INFO - Using buildbot properties: 04:51:55 INFO - { 04:51:55 INFO - "project": "", 04:51:55 INFO - "product": "firefox", 04:51:55 INFO - "who": "archaeopteryx@coole-files.de", 04:51:55 INFO - "installer_path": "public/build/target.tar.bz2", 04:51:55 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 04:51:55 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 04:51:55 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 04:51:55 INFO - "stage_platform": "linux64-stylosequential", 04:51:55 INFO - "basedir": "/builds/slave/test", 04:51:55 INFO - "buildnumber": 47, 04:51:55 INFO - "platform": "ubuntu64_hw_styloseq", 04:51:55 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:51:55 INFO - "slavebuilddir": "test", 04:51:55 INFO - "taskId": "XqtRntARR4uQDs5TwdWiCg", 04:51:55 INFO - "branch": "mozilla-central", 04:51:55 INFO - "script_repo_revision": "production", 04:51:55 INFO - "revision": "3bbd8e25df3d92464b97bcb42413febd9041af6f", 04:51:55 INFO - "slavename": "talos-linux64-ix-071", 04:51:55 INFO - "repo_path": "mozilla-central" 04:51:55 INFO - } 04:51:55 INFO - Finding installer, test and symbols from parent task. 04:51:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XqtRntARR4uQDs5TwdWiCg'}, attempt #1 04:51:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XqtRntARR4uQDs5TwdWiCg'}, attempt #1 04:51:56 INFO - Task dependencies: Yd1KAK7PTkGgzWZxFL4DBA 04:51:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA'}, attempt #1 04:51:56 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.tar.bz2 04:51:56 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.test_packages.json 04:51:56 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.crashreporter-symbols.zip 04:51:56 INFO - Running post-action listener: _resource_record_post_action 04:51:56 INFO - [mozharness: 2017-08-25 11:51:56.869455Z] Finished read-buildbot-config step (success) 04:51:56 INFO - [mozharness: 2017-08-25 11:51:56.869673Z] Running download-and-extract step. 04:51:56 INFO - Running pre-action listener: _resource_record_pre_action 04:51:56 INFO - Running main action method: download_and_extract 04:51:56 INFO - mkdir: /builds/slave/test/build/tests 04:51:56 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')]} 04:51:56 INFO - trying https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.test_packages.json 04:51:56 INFO - Downloading https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:51:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:51:57 INFO - Downloaded 989 bytes. 04:51:57 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:51:57 INFO - Using the following test package requirements: 04:51:57 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:51:57 INFO - u'common': [u'target.common.tests.zip'], 04:51:57 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:51:57 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:51:57 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:51:57 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:51:57 INFO - u'mozbase': [u'target.common.tests.zip'], 04:51:57 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:51:57 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:51:57 INFO - u'web-platform': [u'target.common.tests.zip', 04:51:57 INFO - u'target.web-platform.tests.tar.gz'], 04:51:57 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:51:57 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 04:51:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.common.tests.zip 04:51:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:51:57 INFO - Fetch https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.common.tests.zip into memory 04:51:58 INFO - Content-Length response header: 54980217 04:51:58 INFO - Bytes received: 54980217 04:52:09 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 04:52:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.common.tests.zip 04:52:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:52:09 INFO - Fetch https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.common.tests.zip into memory 04:52:10 INFO - Content-Length response header: 54980217 04:52:10 INFO - Bytes received: 54980217 04:52:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.talos.tests.zip 04:52:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:52:22 INFO - Fetch https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.talos.tests.zip into memory 04:52:23 INFO - Content-Length response header: 14496611 04:52:23 INFO - Bytes received: 14496611 04:52:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:23 INFO - trying https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.tar.bz2 04:52:23 INFO - Downloading https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:52:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:52:24 INFO - Downloaded 61310919 bytes. 04:52:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.tar.bz2 04:52:24 INFO - mkdir: /builds/slave/test/properties 04:52:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:52:24 INFO - Writing to file /builds/slave/test/properties/build_url 04:52:24 INFO - Contents: 04:52:24 INFO - build_url:https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.tar.bz2 04:52:24 INFO - Running post-action listener: _resource_record_post_action 04:52:24 INFO - Running post-action listener: find_tests_for_verification 04:52:24 INFO - Running post-action listener: set_extra_try_arguments 04:52:24 INFO - [mozharness: 2017-08-25 11:52:24.438475Z] Finished download-and-extract step (success) 04:52:24 INFO - [mozharness: 2017-08-25 11:52:24.438571Z] Running populate-webroot step. 04:52:24 INFO - Running pre-action listener: _resource_record_pre_action 04:52:24 INFO - Running main action method: populate_webroot 04:52:24 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:52:24 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 04:52:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:52:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:52:24 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 04:52:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:52:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:52:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:52:24 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 04:52:24 INFO - 'talos_options': ['--stylo'], 04:52:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:52:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:52:24 INFO - 'tests': ['damp', 'tps']}, 04:52:24 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 04:52:24 INFO - 'talos_options': ['--stylo'], 04:52:24 INFO - 'tests': ['damp', 'tps']}, 04:52:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:52:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:52:24 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 04:52:24 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 04:52:24 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:52:24 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:52:24 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 04:52:24 INFO - 'talos_options': ['--stylo'], 04:52:24 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:52:24 INFO - 'other-e10s': {'tests': ['a11yr', 04:52:24 INFO - 'ts_paint', 04:52:24 INFO - 'tpaint', 04:52:24 INFO - 'sessionrestore', 04:52:24 INFO - 'sessionrestore_many_windows', 04:52:24 INFO - 'sessionrestore_no_auto_restore', 04:52:24 INFO - 'tabpaint']}, 04:52:24 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 04:52:24 INFO - 'tests': ['a11yr', 04:52:24 INFO - 'ts_paint', 04:52:24 INFO - 'tpaint', 04:52:24 INFO - 'sessionrestore', 04:52:24 INFO - 'sessionrestore_many_windows', 04:52:24 INFO - 'sessionrestore_no_auto_restore', 04:52:24 INFO - 'tabpaint']}, 04:52:24 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 04:52:24 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 04:52:24 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 04:52:24 INFO - 'tests': ['bloom_basic_singleton']}, 04:52:24 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 04:52:24 INFO - 'tests': ['bloom_basic']}, 04:52:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:52:24 INFO - 'tsvgr_opacity', 04:52:24 INFO - 'tart', 04:52:24 INFO - 'tscrollx', 04:52:24 INFO - 'tsvg_static']}, 04:52:24 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 04:52:24 INFO - 'tests': ['tsvgx', 04:52:24 INFO - 'tsvgr_opacity', 04:52:24 INFO - 'tart', 04:52:24 INFO - 'tscrollx', 04:52:24 INFO - 'tsvg_static']}, 04:52:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:52:24 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 04:52:24 INFO - 'talos_options': ['--stylo'], 04:52:24 INFO - 'tests': ['tp5o']}, 04:52:24 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:52:24 INFO - 'talos_options': ['--mitmproxy', 04:52:24 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:52:24 INFO - '--firstNonBlankPaint'], 04:52:24 INFO - 'tests': ['tp6_google', 04:52:24 INFO - 'tp6_youtube', 04:52:24 INFO - 'tp6_amazon', 04:52:24 INFO - 'tp6_facebook']}, 04:52:24 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:52:24 INFO - 'talos_options': ['--stylo', 04:52:24 INFO - '--mitmproxy', 04:52:24 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:52:24 INFO - '--firstNonBlankPaint'], 04:52:24 INFO - 'tests': ['tp6_google', 04:52:24 INFO - 'tp6_youtube', 04:52:24 INFO - 'tp6_amazon', 04:52:24 INFO - 'tp6_facebook']}, 04:52:24 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:52:24 INFO - 'talos_options': ['--stylo', 04:52:24 INFO - '--stylo-threads=1', 04:52:24 INFO - '--mitmproxy', 04:52:24 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:52:24 INFO - '--firstNonBlankPaint'], 04:52:24 INFO - 'tests': ['tp6_google', 04:52:24 INFO - 'tp6_youtube', 04:52:24 INFO - 'tp6_amazon', 04:52:24 INFO - 'tp6_facebook']}, 04:52:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:52:24 INFO - 'talos_options': ['--xperf_path', 04:52:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:52:24 INFO - 'tests': ['tp5n']}, 04:52:24 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 04:52:24 INFO - 'talos_options': ['--stylo', 04:52:24 INFO - '--xperf_path', 04:52:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:52:24 INFO - 'tests': ['tp5n']}}} 04:52:24 INFO - Running post-action listener: _resource_record_post_action 04:52:24 INFO - [mozharness: 2017-08-25 11:52:24.452686Z] Finished populate-webroot step (success) 04:52:24 INFO - [mozharness: 2017-08-25 11:52:24.452776Z] Running create-virtualenv step. 04:52:24 INFO - Running pre-action listener: _resource_record_pre_action 04:52:24 INFO - Running main action method: create_virtualenv 04:52:24 INFO - Creating virtualenv /builds/slave/test/build/venv 04:52:24 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:52:24 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 04:52:24 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:52:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:52:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:52:25 INFO - Installing distribute.............................................................................................................................................................................................done. 04:52:25 INFO - Installing pip...............done. 04:52:25 INFO - Return code: 0 04:52:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:52:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:52:25 INFO - Reading from file tmpfile_stdout 04:52:25 INFO - Output received: 04:52:25 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 04:52:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:52:25 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')]} 04:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:25 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')]} 04:52:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:25 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 0x1a681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c33a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd6f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd4130>, '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 0x1dd5030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd54c0>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', '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 04:52:25 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 04:52:25 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 04:52:25 INFO - Using env: {'DISPLAY': ':0', 04:52:25 INFO - 'HOME': '/home/cltbld', 04:52:25 INFO - 'LANG': 'en_US.UTF-8', 04:52:25 INFO - 'LANGUAGE': 'en_US:en', 04:52:25 INFO - 'LOGNAME': 'cltbld', 04:52:25 INFO - 'MAIL': '/var/mail/cltbld', 04:52:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:25 INFO - 'MOZ_NO_REMOTE': '1', 04:52:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:25 INFO - 'NO_EM_RESTART': '1', 04:52:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:25 INFO - 'PWD': '/builds/slave/test', 04:52:25 INFO - 'SHELL': '/bin/bash', 04:52:25 INFO - 'SHLVL': '1', 04:52:25 INFO - 'TERM': 'linux', 04:52:25 INFO - 'TMOUT': '86400', 04:52:25 INFO - 'USER': 'cltbld', 04:52:25 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', 04:52:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:25 INFO - '_': '/tools/buildbot/bin/python'} 04:52:26 INFO - Ignoring indexes: http://pypi.python.org/simple/ 04:52:26 INFO - Downloading/unpacking pip>=1.5 04:52:26 INFO - Running setup.py egg_info for package pip 04:52:26 INFO - warning: no files found matching 'pip/cacert.pem' 04:52:26 INFO - warning: no files found matching '*.html' under directory 'docs' 04:52:26 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:52:26 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:52:26 INFO - Installing collected packages: pip 04:52:26 INFO - Found existing installation: pip 1.1 04:52:26 INFO - Uninstalling pip: 04:52:26 INFO - Successfully uninstalled pip 04:52:26 INFO - Running setup.py install for pip 04:52:26 INFO - warning: no files found matching 'pip/cacert.pem' 04:52:26 INFO - warning: no files found matching '*.html' under directory 'docs' 04:52:26 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:52:26 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:52:26 INFO - Installing pip script to /builds/slave/test/build/venv/bin 04:52:26 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 04:52:26 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 04:52:26 INFO - Successfully installed pip 04:52:26 INFO - Cleaning up... 04:52:26 INFO - Return code: 0 04:52:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:52: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')]} 04:52:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52: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')]} 04:52:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52: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', '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 0x1a681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c33a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd6f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd4130>, '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 0x1dd5030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd54c0>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', '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 04:52: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', 'psutil>=3.1.1'] in /builds/slave/test/build 04:52: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 psutil>=3.1.1 04:52:26 INFO - Using env: {'DISPLAY': ':0', 04:52:26 INFO - 'HOME': '/home/cltbld', 04:52:26 INFO - 'LANG': 'en_US.UTF-8', 04:52:26 INFO - 'LANGUAGE': 'en_US:en', 04:52:26 INFO - 'LOGNAME': 'cltbld', 04:52:26 INFO - 'MAIL': '/var/mail/cltbld', 04:52:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:26 INFO - 'MOZ_NO_REMOTE': '1', 04:52:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:26 INFO - 'NO_EM_RESTART': '1', 04:52:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:26 INFO - 'PWD': '/builds/slave/test', 04:52:26 INFO - 'SHELL': '/bin/bash', 04:52:26 INFO - 'SHLVL': '1', 04:52:26 INFO - 'TERM': 'linux', 04:52:26 INFO - 'TMOUT': '86400', 04:52:26 INFO - 'USER': 'cltbld', 04:52:26 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', 04:52:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:26 INFO - '_': '/tools/buildbot/bin/python'} 04:52:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:26 INFO - Downloading/unpacking psutil>=3.1.1 04:52:26 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 04:52:26 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 04:52:26 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 04:52:26 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 04:52:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:52:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:52:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:52:28 INFO - Installing collected packages: psutil 04:52:28 INFO - Running setup.py install for psutil 04:52:28 INFO - building 'psutil._psutil_linux' extension 04:52: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 04:52: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 04:52:28 INFO - building 'psutil._psutil_posix' extension 04:52:28 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 04:52: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_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:52:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:52:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:52:28 INFO - Successfully installed psutil 04:52:28 INFO - Cleaning up... 04:52:28 INFO - Return code: 0 04:52:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:52:28 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')]} 04:52:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:28 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')]} 04:52:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:28 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 0x1a681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c33a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd6f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd4130>, '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 0x1dd5030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd54c0>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', '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 04:52:28 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 04:52:28 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 04:52:28 INFO - Using env: {'DISPLAY': ':0', 04:52:28 INFO - 'HOME': '/home/cltbld', 04:52:28 INFO - 'LANG': 'en_US.UTF-8', 04:52:28 INFO - 'LANGUAGE': 'en_US:en', 04:52:28 INFO - 'LOGNAME': 'cltbld', 04:52:28 INFO - 'MAIL': '/var/mail/cltbld', 04:52:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:28 INFO - 'MOZ_NO_REMOTE': '1', 04:52:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:28 INFO - 'NO_EM_RESTART': '1', 04:52:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:28 INFO - 'PWD': '/builds/slave/test', 04:52:28 INFO - 'SHELL': '/bin/bash', 04:52:28 INFO - 'SHLVL': '1', 04:52:28 INFO - 'TERM': 'linux', 04:52:28 INFO - 'TMOUT': '86400', 04:52:28 INFO - 'USER': 'cltbld', 04:52:28 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', 04:52:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:28 INFO - '_': '/tools/buildbot/bin/python'} 04:52:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:52:28 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 04:52:28 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 04:52:28 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 04:52:28 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 04:52:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:52:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:52:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:52:30 INFO - Installing collected packages: mozsystemmonitor 04:52:30 INFO - Running setup.py install for mozsystemmonitor 04:52:30 INFO - Successfully installed mozsystemmonitor 04:52:30 INFO - Cleaning up... 04:52:30 INFO - Return code: 0 04:52:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:52:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:30 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 0x1a681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c33a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd6f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd4130>, '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 0x1dd5030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd54c0>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', '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 04:52:30 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 04:52:30 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 04:52:30 INFO - Using env: {'DISPLAY': ':0', 04:52:30 INFO - 'HOME': '/home/cltbld', 04:52:30 INFO - 'LANG': 'en_US.UTF-8', 04:52:30 INFO - 'LANGUAGE': 'en_US:en', 04:52:30 INFO - 'LOGNAME': 'cltbld', 04:52:30 INFO - 'MAIL': '/var/mail/cltbld', 04:52:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:30 INFO - 'MOZ_NO_REMOTE': '1', 04:52:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:30 INFO - 'NO_EM_RESTART': '1', 04:52:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:30 INFO - 'PWD': '/builds/slave/test', 04:52:30 INFO - 'SHELL': '/bin/bash', 04:52:30 INFO - 'SHLVL': '1', 04:52:30 INFO - 'TERM': 'linux', 04:52:30 INFO - 'TMOUT': '86400', 04:52:30 INFO - 'USER': 'cltbld', 04:52:30 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', 04:52:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:30 INFO - '_': '/tools/buildbot/bin/python'} 04:52:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:30 INFO - Downloading/unpacking jsonschema==2.5.1 04:52:30 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 04:52:30 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 04:52:30 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 04:52:30 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 04:52:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:52:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:52: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 04:52: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 04:52: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 04:52: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 04:52:31 INFO - Downloading functools32-3.2.3-2.tar.gz 04:52:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:52:31 INFO - warning: no files found matching '*.txt' 04:52:31 INFO - no previously-included directories found matching 'build' 04:52:31 INFO - no previously-included directories found matching 'dist' 04:52:31 INFO - no previously-included directories found matching '.git*' 04:52:31 INFO - Installing collected packages: jsonschema, functools32 04:52:31 INFO - Running setup.py install for functools32 04:52:31 INFO - warning: no files found matching '*.txt' 04:52:31 INFO - no previously-included directories found matching 'build' 04:52:31 INFO - no previously-included directories found matching 'dist' 04:52:31 INFO - no previously-included directories found matching '.git*' 04:52:31 INFO - Successfully installed jsonschema functools32 04:52:31 INFO - Cleaning up... 04:52:31 INFO - Return code: 0 04:52:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:52: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')]} 04:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52: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')]} 04:52:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52: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', '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 0x1a681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c33a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd6f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd4130>, '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 0x1dd5030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd54c0>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', '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 04:52: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:52: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 functools32==3.2.3-2 04:52:31 INFO - Using env: {'DISPLAY': ':0', 04:52:31 INFO - 'HOME': '/home/cltbld', 04:52:31 INFO - 'LANG': 'en_US.UTF-8', 04:52:31 INFO - 'LANGUAGE': 'en_US:en', 04:52:31 INFO - 'LOGNAME': 'cltbld', 04:52:31 INFO - 'MAIL': '/var/mail/cltbld', 04:52:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:31 INFO - 'MOZ_NO_REMOTE': '1', 04:52:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:31 INFO - 'NO_EM_RESTART': '1', 04:52:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:31 INFO - 'PWD': '/builds/slave/test', 04:52:31 INFO - 'SHELL': '/bin/bash', 04:52:31 INFO - 'SHLVL': '1', 04:52:31 INFO - 'TERM': 'linux', 04:52:31 INFO - 'TMOUT': '86400', 04:52:31 INFO - 'USER': 'cltbld', 04:52:31 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', 04:52:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:31 INFO - '_': '/tools/buildbot/bin/python'} 04:52:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:52:31 INFO - Cleaning up... 04:52:32 INFO - Return code: 0 04:52:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:52:32 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')]} 04:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:32 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')]} 04:52:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:32 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 0x1a681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c33a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd6f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd4130>, '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 0x1dd5030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd54c0>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', '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 04:52:32 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 04:52:32 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 04:52:32 INFO - Using env: {'DISPLAY': ':0', 04:52:32 INFO - 'HOME': '/home/cltbld', 04:52:32 INFO - 'LANG': 'en_US.UTF-8', 04:52:32 INFO - 'LANGUAGE': 'en_US:en', 04:52:32 INFO - 'LOGNAME': 'cltbld', 04:52:32 INFO - 'MAIL': '/var/mail/cltbld', 04:52:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:32 INFO - 'MOZ_NO_REMOTE': '1', 04:52:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:32 INFO - 'NO_EM_RESTART': '1', 04:52:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:32 INFO - 'PWD': '/builds/slave/test', 04:52:32 INFO - 'SHELL': '/bin/bash', 04:52:32 INFO - 'SHLVL': '1', 04:52:32 INFO - 'TERM': 'linux', 04:52:32 INFO - 'TMOUT': '86400', 04:52:32 INFO - 'USER': 'cltbld', 04:52:32 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', 04:52:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:32 INFO - '_': '/tools/buildbot/bin/python'} 04:52:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:32 INFO - Downloading/unpacking blobuploader==1.2.4 04:52: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 04:52: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 04:52: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 04:52: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 04:52:33 INFO - Downloading blobuploader-1.2.4.tar.gz 04:52:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:52:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:52:33 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 04:52:33 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 04:52:33 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 04:52:33 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 04:52:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:52:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:52:33 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 04:52:33 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 04:52:33 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 04:52:33 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 04:52:33 INFO - Downloading docopt-0.6.1.tar.gz 04:52:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:52:33 INFO - Installing collected packages: blobuploader, requests, docopt 04:52:33 INFO - Running setup.py install for blobuploader 04:52:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:52:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:52:34 INFO - Running setup.py install for requests 04:52:34 INFO - Running setup.py install for docopt 04:52:34 INFO - Successfully installed blobuploader requests docopt 04:52:34 INFO - Cleaning up... 04:52:34 INFO - Return code: 0 04:52:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:52: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')]} 04:52:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52: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')]} 04:52:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:34 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 0x1a681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c33a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd6f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd4130>, '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 0x1dd5030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd54c0>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', '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 04:52:34 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 04:52:34 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 04:52:34 INFO - Using env: {'DISPLAY': ':0', 04:52:34 INFO - 'HOME': '/home/cltbld', 04:52:34 INFO - 'LANG': 'en_US.UTF-8', 04:52:34 INFO - 'LANGUAGE': 'en_US:en', 04:52:34 INFO - 'LOGNAME': 'cltbld', 04:52:34 INFO - 'MAIL': '/var/mail/cltbld', 04:52:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:34 INFO - 'MOZ_NO_REMOTE': '1', 04:52:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:34 INFO - 'NO_EM_RESTART': '1', 04:52:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:34 INFO - 'PWD': '/builds/slave/test', 04:52:34 INFO - 'SHELL': '/bin/bash', 04:52:34 INFO - 'SHLVL': '1', 04:52:34 INFO - 'TERM': 'linux', 04:52:34 INFO - 'TMOUT': '86400', 04:52:34 INFO - 'USER': 'cltbld', 04:52:34 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', 04:52:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:34 INFO - '_': '/tools/buildbot/bin/python'} 04:52:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:52:34 INFO - Running setup.py (path:/tmp/pip-Bl22DJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:52:34 INFO - Running setup.py (path:/tmp/pip-8fWstK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:52:34 INFO - Running setup.py (path:/tmp/pip-0IEM4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:52:34 INFO - Running setup.py (path:/tmp/pip-YQp4Gk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:52:34 INFO - Running setup.py (path:/tmp/pip-wubQvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:52:35 INFO - Running setup.py (path:/tmp/pip-VUyEKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:52:35 INFO - Running setup.py (path:/tmp/pip-AWpA_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:52:35 INFO - Running setup.py (path:/tmp/pip-kfAoJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:52:35 INFO - Running setup.py (path:/tmp/pip-UVnhG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:52:35 INFO - Running setup.py (path:/tmp/pip-x8e4v4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:52:35 INFO - Running setup.py (path:/tmp/pip-wnLMmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:52:35 INFO - Running setup.py (path:/tmp/pip-_rSnFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:52:35 INFO - Running setup.py (path:/tmp/pip-1uVsCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:52:35 INFO - Running setup.py (path:/tmp/pip-hTvaYC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:52:35 INFO - Running setup.py (path:/tmp/pip-ZJ_WLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:52:35 INFO - Running setup.py (path:/tmp/pip-4vPTA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:52:35 INFO - Running setup.py (path:/tmp/pip-pV98So-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:52:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:52:35 INFO - Running setup.py install for manifestparser 04:52:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:52:36 INFO - Running setup.py install for mozcrash 04:52:36 INFO - Running setup.py install for mozdebug 04:52:36 INFO - Running setup.py install for mozdevice 04:52:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:52:36 INFO - Running setup.py install for mozfile 04:52:36 INFO - Running setup.py install for mozhttpd 04:52:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:52:36 INFO - Running setup.py install for mozinfo 04:52:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:52:36 INFO - Running setup.py install for mozInstall 04:52:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:52:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:52:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:52:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:52:36 INFO - Running setup.py install for mozleak 04:52:36 INFO - Running setup.py install for mozlog 04:52:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:52:36 INFO - Running setup.py install for moznetwork 04:52:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:52:37 INFO - Running setup.py install for mozprocess 04:52:37 INFO - Running setup.py install for mozprofile 04:52:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:52:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:52:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:52:37 INFO - Running setup.py install for mozrunner 04:52:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:52:37 INFO - Running setup.py install for mozscreenshot 04:52:37 INFO - Running setup.py install for moztest 04:52:37 INFO - Running setup.py install for mozversion 04:52:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:52:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:52:37 INFO - Cleaning up... 04:52:37 INFO - Return code: 0 04:52:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:52:37 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')]} 04:52:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:37 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')]} 04:52:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:37 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 0x1a681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c33a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd6f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd4130>, '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 0x1dd5030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd54c0>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', '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 04:52:37 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 04:52:37 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 04:52:37 INFO - Using env: {'DISPLAY': ':0', 04:52:37 INFO - 'HOME': '/home/cltbld', 04:52:37 INFO - 'LANG': 'en_US.UTF-8', 04:52:37 INFO - 'LANGUAGE': 'en_US:en', 04:52:37 INFO - 'LOGNAME': 'cltbld', 04:52:37 INFO - 'MAIL': '/var/mail/cltbld', 04:52:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:37 INFO - 'MOZ_NO_REMOTE': '1', 04:52:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:37 INFO - 'NO_EM_RESTART': '1', 04:52:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:37 INFO - 'PWD': '/builds/slave/test', 04:52:37 INFO - 'SHELL': '/bin/bash', 04:52:37 INFO - 'SHLVL': '1', 04:52:37 INFO - 'TERM': 'linux', 04:52:37 INFO - 'TMOUT': '86400', 04:52:37 INFO - 'USER': 'cltbld', 04:52:37 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', 04:52:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:37 INFO - '_': '/tools/buildbot/bin/python'} 04:52:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:52:37 INFO - Running setup.py (path:/tmp/pip-fGb1tj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:52:37 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)) 04:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:52:37 INFO - Running setup.py (path:/tmp/pip-vwdleM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:52:38 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)) 04:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:52:38 INFO - Running setup.py (path:/tmp/pip-zIlsyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:52:38 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)) 04:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:52:38 INFO - Running setup.py (path:/tmp/pip-o7fTbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:52:38 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)) 04:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:52:38 INFO - Running setup.py (path:/tmp/pip-TICThA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:52:38 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)) 04:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:52:38 INFO - Running setup.py (path:/tmp/pip-Ol1EW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:52:38 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)) 04:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:52:38 INFO - Running setup.py (path:/tmp/pip-0lyO4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:52:38 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)) 04:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:52:38 INFO - Running setup.py (path:/tmp/pip-fOPOp0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:52:38 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)) 04:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:52:38 INFO - Running setup.py (path:/tmp/pip-IP3Mb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:52:38 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)) 04:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:52:38 INFO - Running setup.py (path:/tmp/pip-XoyebX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:52:38 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)) 04:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:52:38 INFO - Running setup.py (path:/tmp/pip-mQBgQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:52:38 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)) 04:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:52:38 INFO - Running setup.py (path:/tmp/pip-XCNNrE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:52:38 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)) 04:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:52:38 INFO - Running setup.py (path:/tmp/pip-CxpvGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:52:38 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)) 04:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:52:38 INFO - Running setup.py (path:/tmp/pip-2P9AaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:52:38 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)) 04:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:52:38 INFO - Running setup.py (path:/tmp/pip-tQzgfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:52:38 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)) 04:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:52:38 INFO - Running setup.py (path:/tmp/pip-hjFSlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:52:39 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)) 04:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:52:39 INFO - Running setup.py (path:/tmp/pip-yP45gk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:52:39 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)) 04:52:39 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)) 04:52:39 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)) 04:52:39 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)) 04:52:39 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)) 04:52:39 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)) 04:52:39 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)) 04:52:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:52:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:40 INFO - Downloading blessings-1.6.tar.gz 04:52:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:52:40 INFO - Installing collected packages: blessings 04:52:40 INFO - Running setup.py install for blessings 04:52:40 INFO - Successfully installed blessings 04:52:40 INFO - Cleaning up... 04:52:40 INFO - Return code: 0 04:52:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:52:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:52:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:52:40 INFO - Reading from file tmpfile_stdout 04:52:40 INFO - Current package versions: 04:52:40 INFO - argparse == 1.2.1 04:52:40 INFO - blessings == 1.6 04:52:40 INFO - blobuploader == 1.2.4 04:52:40 INFO - docopt == 0.6.1 04:52:40 INFO - functools32 == 3.2.3-2 04:52:40 INFO - jsonschema == 2.5.1 04:52:40 INFO - manifestparser == 1.1 04:52:40 INFO - mozInstall == 1.13 04:52:40 INFO - mozcrash == 1.0 04:52:40 INFO - mozdebug == 0.1 04:52:40 INFO - mozdevice == 0.51 04:52:40 INFO - mozfile == 1.2 04:52:40 INFO - mozhttpd == 0.7 04:52:40 INFO - mozinfo == 0.10 04:52:40 INFO - mozleak == 0.1 04:52:40 INFO - mozlog == 3.5 04:52:40 INFO - moznetwork == 0.27 04:52:40 INFO - mozprocess == 0.25 04:52:40 INFO - mozprofile == 0.28 04:52:40 INFO - mozrunner == 6.13 04:52:40 INFO - mozscreenshot == 0.1 04:52:40 INFO - mozsystemmonitor == 0.3 04:52:40 INFO - moztest == 0.8 04:52:40 INFO - mozversion == 1.4 04:52:40 INFO - psutil == 3.1.1 04:52:40 INFO - requests == 1.2.3 04:52:40 INFO - wsgiref == 0.1.2 04:52:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:52:40 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')]} 04:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:40 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')]} 04:52:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:40 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 0x1a681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c33a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd6f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd4130>, '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 0x1dd5030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd54c0>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', '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 04:52:40 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 04:52:40 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 04:52:40 INFO - Using env: {'DISPLAY': ':0', 04:52:40 INFO - 'HOME': '/home/cltbld', 04:52:40 INFO - 'LANG': 'en_US.UTF-8', 04:52:40 INFO - 'LANGUAGE': 'en_US:en', 04:52:40 INFO - 'LOGNAME': 'cltbld', 04:52:40 INFO - 'MAIL': '/var/mail/cltbld', 04:52:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:40 INFO - 'MOZ_NO_REMOTE': '1', 04:52:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:40 INFO - 'NO_EM_RESTART': '1', 04:52:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:40 INFO - 'PWD': '/builds/slave/test', 04:52:40 INFO - 'SHELL': '/bin/bash', 04:52:40 INFO - 'SHLVL': '1', 04:52:40 INFO - 'TERM': 'linux', 04:52:40 INFO - 'TMOUT': '86400', 04:52:40 INFO - 'USER': 'cltbld', 04:52:40 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', 04:52:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:40 INFO - '_': '/tools/buildbot/bin/python'} 04:52:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:40 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)) 04:52:40 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)) 04:52:40 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)) 04:52:40 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)) 04:52:40 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)) 04:52:40 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)) 04:52:40 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)) 04:52:40 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)) 04:52:40 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)) 04:52:40 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:52:40 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 04:52:40 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 04:52:40 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 04:52:40 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 04:52:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 04:52:42 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)) 04:52:42 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)) 04:52:42 INFO - Installing collected packages: simplejson 04:52:42 INFO - Running setup.py install for simplejson 04:52:42 INFO - building 'simplejson._speedups' extension 04:52:42 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 04:52:42 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 04:52:43 INFO - Successfully installed simplejson 04:52:43 INFO - Cleaning up... 04:52:43 INFO - Return code: 0 04:52:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:52:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c33a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd6f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd4130>, '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 0x1dd5030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd54c0>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', '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 04:52:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:52:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:52:43 INFO - Using env: {'DISPLAY': ':0', 04:52:43 INFO - 'HOME': '/home/cltbld', 04:52:43 INFO - 'LANG': 'en_US.UTF-8', 04:52:43 INFO - 'LANGUAGE': 'en_US:en', 04:52:43 INFO - 'LOGNAME': 'cltbld', 04:52:43 INFO - 'MAIL': '/var/mail/cltbld', 04:52:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:43 INFO - 'MOZ_NO_REMOTE': '1', 04:52:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:43 INFO - 'NO_EM_RESTART': '1', 04:52:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:43 INFO - 'PWD': '/builds/slave/test', 04:52:43 INFO - 'SHELL': '/bin/bash', 04:52:43 INFO - 'SHLVL': '1', 04:52:43 INFO - 'TERM': 'linux', 04:52:43 INFO - 'TMOUT': '86400', 04:52:43 INFO - 'USER': 'cltbld', 04:52:43 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', 04:52:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:43 INFO - '_': '/tools/buildbot/bin/python'} 04:52:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:52:43 INFO - Cleaning up... 04:52:43 INFO - Return code: 0 04:52:43 INFO - Running post-action listener: _resource_record_post_action 04:52:43 INFO - Running post-action listener: _start_resource_monitoring 04:52:43 INFO - Starting resource monitoring. 04:52:43 INFO - [mozharness: 2017-08-25 11:52:43.342848Z] Finished create-virtualenv step (success) 04:52:43 INFO - [mozharness: 2017-08-25 11:52:43.343366Z] Running install step. 04:52:43 INFO - Running pre-action listener: _resource_record_pre_action 04:52:43 INFO - Running main action method: install 04:52:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:52:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:52:43 INFO - Reading from file tmpfile_stdout 04:52:43 INFO - Detecting whether we're running mozinstall >=1.0... 04:52:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:52:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:52:43 INFO - Reading from file tmpfile_stdout 04:52:43 INFO - Output received: 04:52:43 INFO - Usage: mozinstall [options] installer 04:52:43 INFO - Options: 04:52:43 INFO - -h, --help show this help message and exit 04:52:43 INFO - -d DEST, --destination=DEST 04:52:43 INFO - Directory to install application into. [default: 04:52:43 INFO - "/builds/slave/test"] 04:52:43 INFO - --app=APP Application being installed. [default: firefox] 04:52:43 INFO - mkdir: /builds/slave/test/build/application 04:52:43 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'] 04:52:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:53:01 INFO - Reading from file tmpfile_stdout 04:53:01 INFO - Output received: 04:53:01 INFO - /builds/slave/test/build/application/firefox/firefox 04:53:01 INFO - Running post-action listener: _resource_record_post_action 04:53:01 INFO - [mozharness: 2017-08-25 11:53:01.431184Z] Finished install step (success) 04:53:01 INFO - [mozharness: 2017-08-25 11:53:01.431436Z] Running setup-mitmproxy step. 04:53:01 INFO - Running pre-action listener: _resource_record_pre_action 04:53:01 INFO - Running main action method: setup_mitmproxy 04:53:01 INFO - Skipping: mitmproxy is not required 04:53:01 INFO - Running post-action listener: _resource_record_post_action 04:53:01 INFO - [mozharness: 2017-08-25 11:53:01.432560Z] Finished setup-mitmproxy step (success) 04:53:01 INFO - [mozharness: 2017-08-25 11:53:01.432780Z] Running run-tests step. 04:53:01 INFO - Running pre-action listener: _resource_record_pre_action 04:53:01 INFO - Running pre-action listener: _set_gcov_prefix 04:53:01 INFO - Running main action method: run_tests 04:53:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:53:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:53:01 INFO - Python 2.7.3 04:53:01 INFO - Return code: 0 04:53:01 INFO - grabbing minidump binary from tooltool 04:53:01 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')]} 04:53:01 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 0x1dd4130>, '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 0x1dd5030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd54c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:53:01 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 04:53:01 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 04:53:01 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 04:53:01 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:53:01 INFO - Return code: 0 04:53:01 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:53:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:53:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:53:01 INFO - ENV: RUST_BACKTRACE is now full 04:53:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:53:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:53:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-071', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:53:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-071 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:53:01 INFO - Using env: {'DISPLAY': ':0', 04:53:01 INFO - 'HOME': '/home/cltbld', 04:53:01 INFO - 'LANG': 'en_US.UTF-8', 04:53:01 INFO - 'LANGUAGE': 'en_US:en', 04:53:01 INFO - 'LOGNAME': 'cltbld', 04:53:01 INFO - 'MAIL': '/var/mail/cltbld', 04:53:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:53:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:01 INFO - 'MOZ_NO_REMOTE': '1', 04:53:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:53:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:53:01 INFO - 'NO_EM_RESTART': '1', 04:53:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:01 INFO - 'PWD': '/builds/slave/test', 04:53:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:53:01 INFO - 'RUST_BACKTRACE': 'full', 04:53:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:53:01 INFO - 'SHELL': '/bin/bash', 04:53:01 INFO - 'SHLVL': '1', 04:53:01 INFO - 'STYLO_FORCE_ENABLED': '1', 04:53:01 INFO - 'TERM': 'linux', 04:53:01 INFO - 'TMOUT': '86400', 04:53:01 INFO - 'USER': 'cltbld', 04:53:01 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', 04:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:01 INFO - '_': '/tools/buildbot/bin/python'} 04:53:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-071', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:53:01 INFO - mozversion application_buildid: 20170825110814 04:53:01 INFO - mozversion application_changeset: 3bbd8e25df3d92464b97bcb42413febd9041af6f 04:53:01 INFO - mozversion application_display_name: Nightly 04:53:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:53:01 INFO - mozversion application_name: Firefox 04:53:01 INFO - mozversion application_remotingname: firefox 04:53:01 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:53:01 INFO - mozversion application_vendor: Mozilla 04:53:01 INFO - mozversion application_version: 57.0a1 04:53:01 INFO - mozversion platform_buildid: 20170825110814 04:53:01 INFO - mozversion platform_changeset: 3bbd8e25df3d92464b97bcb42413febd9041af6f 04:53:01 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:53:01 INFO - mozversion platform_version: 57.0a1 04:53:01 INFO - using testdate: 1503661981 04:53:01 INFO - actual date: 1503661981 04:53:01 INFO - starting webserver on 'localhost:34066' 04:53:01 INFO - SUITE-START | Running 2 tests 04:53:01 INFO - TEST-START | dromaeo_css 04:53:01 INFO - Initialising browser for dromaeo_css test... 04:53:01 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34066/getInfo.html -profile /tmp/tmphzSIxA/profile 04:53:01 INFO - TEST-INFO | started process 20841 (/builds/slave/test/build/application/firefox/firefox http://localhost:34066/getInfo.html) 04:53:07 INFO - TEST-INFO | 20841: exit 0 04:53:07 INFO - Browser initialized. 04:53:07 INFO - Running cycle 1/1 for dromaeo_css test... 04:53:07 INFO - Using env: {'DISPLAY': ':0', 04:53:07 INFO - 'HOME': '/home/cltbld', 04:53:07 INFO - 'JSGC_DISABLE_POISONING': '1', 04:53:07 INFO - 'LANG': 'en_US.UTF-8', 04:53:07 INFO - 'LANGUAGE': 'en_US:en', 04:53:07 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:53:07 INFO - 'LOGNAME': 'cltbld', 04:53:07 INFO - 'MAIL': '/var/mail/cltbld', 04:53:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:53:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:53:07 INFO - 'MOZ_CRASHREPORTER': '1', 04:53:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:07 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:53:07 INFO - 'MOZ_NO_REMOTE': '1', 04:53:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:53:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:53:07 INFO - 'NO_EM_RESTART': '1', 04:53:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:53:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:07 INFO - 'PWD': '/builds/slave/test', 04:53:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:53:07 INFO - 'RUST_BACKTRACE': 'full', 04:53:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:53:07 INFO - 'SHELL': '/bin/bash', 04:53:07 INFO - 'SHLVL': '1', 04:53:07 INFO - 'STYLO_FORCE_ENABLED': '1', 04:53:07 INFO - 'TERM': 'linux', 04:53:07 INFO - 'TMOUT': '86400', 04:53:07 INFO - 'USER': 'cltbld', 04:53:07 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', 04:53:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:07 INFO - '_': '/tools/buildbot/bin/python'} 04:53:07 INFO - TEST-INFO | started process 21028 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphzSIxA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:53:08 INFO - PID 21028 | 04:53:08 INFO - PID 21028 | (/builds/slave/test/build/application/firefox/firefox:21077): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:53:08 INFO - PID 21028 | 04:53:09 INFO - PID 21028 | 04:53:09 INFO - PID 21028 | (/builds/slave/test/build/application/firefox/firefox:21139): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:53:09 INFO - PID 21028 | 04:53:09 INFO - PID 21028 | __metrics Screen width/height:1600/1200 04:53:09 INFO - PID 21028 | colorDepth:24 04:53:09 INFO - PID 21028 | Browser inner width/height: 1024/768 04:53:09 INFO - PID 21028 | __metrics 04:53:19 INFO - PID 21028 | RSS: Main: 174497792 04:53:19 INFO - PID 21028 | 04:56:18 INFO - PID 21028 | Cycle 1(1): loaded http://localhost:34066/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34066/tests/dromaeo/cssquery-ext.html) 04:56:19 INFO - PID 21028 | RSS: Main: 178503680 04:56:19 INFO - PID 21028 | 04:59:21 INFO - PID 21028 | Cycle 1(1): loaded http://localhost:34066/tests/dromaeo/cssquery-ext.html (next: http://localhost:34066/tests/dromaeo/cssquery-jquery.html) 04:59:22 INFO - PID 21028 | RSS: Main: 174628864 04:59:22 INFO - PID 21028 | 05:02:23 INFO - PID 21028 | Cycle 1(1): loaded http://localhost:34066/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34066/tests/dromaeo/cssquery-mootools.html) 05:02:24 INFO - PID 21028 | RSS: Main: 175489024 05:02:24 INFO - PID 21028 | 05:05:26 INFO - PID 21028 | Cycle 1(1): loaded http://localhost:34066/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34066/tests/dromaeo/cssquery-prototype.html) 05:05:26 INFO - PID 21028 | RSS: Main: 175280128 05:05:26 INFO - PID 21028 | 05:08:30 INFO - PID 21028 | Cycle 1(1): loaded http://localhost:34066/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34066/tests/dromaeo/cssquery-yui.html) 05:08:31 INFO - PID 21028 | RSS: Main: 179163136 05:08:31 INFO - PID 21028 | 05:11:33 INFO - PID 21028 | Cycle 1(1): loaded http://localhost:34066/tests/dromaeo/cssquery-yui.html (next: http://localhost:34066/tests/dromaeo/cssquery-dojo.html) 05:11:33 INFO - PID 21028 | RSS: Main: 178360320 05:11:33 INFO - PID 21028 | 05:11:33 INFO - PID 21028 | __start_tp_report 05:11:33 INFO - PID 21028 | _x_x_mozilla_page_load 05:11:33 INFO - PID 21028 | _x_x_mozilla_page_load_details 05:11:33 INFO - PID 21028 | |i|pagename|runs| 05:11:33 INFO - PID 21028 | |0;dojo.html;3280.983595082025;3286.704196622302;3307.1732066983245;3312.7515436342283;3317.9336413271735;6335.334789847069;6366.808995730135;6402.871942561146;6420.06908998195;6449.806505804821;6243.375662433755;6277.2153480814895;6321.462675672573;6325.557210995232;6360.777372791953;5754.712264386785;5824.213731146294;5839.153322768196;5858.12128180772;5865.85335366615;5904.852378690524;5919.911201331984;5947.048472244442;5947.137665038566;5971.850703732398;5876.676782776945;5905.2323197984415;5911.320198177222;5994.490468310199;6012.398760123996;5645.604807663465;5792.710364481801;5799.797007104773;5915.733791979371;5950.8809823803285;5802.908875037078;5806.854828629318;5864.501517371029;5909.202257695242;5913.201717768132;6183.134361189437;6261.937380626181;6317.210348706411;6351.333110023474;6433.099366088752;6615.702293396808;6724.260331363546;6729.899051514231;6757.898631520531;6892.862142757167;6915.51591388603;6922.06552115463;6929.029935808991;6933.064036355128;6971.581705097659;6381.042843573501;6384;6466.902996455057;6476.222853257592;6478.384553467366;6882.277360877036;7022.6488675566015;7047.471439642048;7110.0401445804155;7127.358537731526;6612.636305003271;6685.331466853276;6691.029800678874;6696.8995465068065;6697.062411262427;6771.424428923597;6776.898346524806;6784.93215067843;6790.049393084873;6854.691538880663;6838.48711346651;6859.108315918998;6879.277675844064;6900.447964162855;6923.2038315594355;6778.051072849805;6815.216250131299;6888.207856096613;6889.689963951634;6892.620905850226;5353.38436079848;5354.491323324278;5421.918671219935;5448.700321482277;5449.700266485382;4623.283391074388;4669.252919532926;4669.34629151919;4728.0543891221205;4809.350737650372;5984.910226346608;5985.850353741192;5997.010493268649;6009.519238460913;6009.699515024231;6839.076724642209;6905.585664860125;6915.273896240822;6924.549904256207;6932.23745388007;5940.227770389822;5958.404159584007;6028.698565071817;6034.456898879122;6038.30559485656;5763.971180144156;5844.503217226498;5853.590248682552;5904.911426328523;5943.3462319144855;6672.933270667231;6698.497612679069;6706.664666766642;6708.094407255056;6714.294999025032;6849.554778939353;6853.965730171317;6940.201876784033;6941.965290173517;6946.65266736651;6596.769113081211;6610.9669451652435;6703.29615390377;6703.396694297537;6714.83212919681;6570.47436205112;6665.566738162004;6699.43054840344;6717.126773519607;6790.354916282945;6813.182418109858;6894.517383783087;6899.344562266577;6910.723571057101;6915.273896240822;6611.173603299588;6618.106555614931;6624.403803657694;6694.0628312037325;6708.396244338033;6638.867222655616;6667.966660166862;6700.765473208415;6707.228668702966;6720.966395167993;6327;6443.548951573345;6454.580452270588;6538.1500404947055;6552.541322107407;6696.83257918556;6707.3628005339415;6724.394804467622;6739.157605299338;6781.220159681699;6358.459530939737;6370.77702280418;6426.196725409324;6429.646369449725;6535.803925882231 05:11:33 INFO - PID 21028 | |1;ext.html;787.1105650614804;838.4969018588847;865.1607940297911;866.726981000985;1098.8296813993834;28989.855050724742;29686.851565742167;37390.25219495608;39022.8048859756;39036.41445378322;39749.8012509937;39939.60060399395;40128.799356003256;40153.598464015355;40201;7579.393648508134;7621.695132194719;7755.185705500926;7776.4945278557;7791.727289544867;7566.432517561205;7678.308952194301;7680.615969201517;7683.039620047494;7686.231376862297;7681.769546913573;7742.496737712059;7747.147813740484;7749.535027898316;7750.457467977244;7656.3109320161175;7676.961615191888;7691.192424795372;7705.653245603933;7708.768736937873;7587.772366829004;7614.733484328077;7617.847643047108;7625.199354067799;7632.656530456087;35209.64790352115;35433.46849797255;35445.29109417823;35476.11309717252;35541.28917421663;3419.64093770153;3458.429359155736;3458.602260739997;3460.8961731148106;3475.548178736748;29923.25191870199;29968;29974.100776976724;29981.850090749624;30026.399472010657;5131.230315452674;5134.306868572732;5177.948220517822;5223.503767142115;5229.08491014071;183943;185350;185541;185656;185991;45999.31001034987;46039.76980115078;46109.538904611196;46195.76902115468;46216;47565.859536320946;47610.285845712344;47643;47646.52353476421;47659.285110723366;14140.646483837987;14353.354099065567;14369.497067602584;14373.712525749426;14381.352839122264;10204.642837500653;10338.328008679411;10399.168066554657;11524.250923689933;11749;13520.729585408237;13526.188428694113;13527.86472135266;13561.660958476117;13684.589462316146;5562.471565201299;5624.85937851557;5644.3508996465935;5664.320281566238;5679.062954612526;989.1394486796534;1005.8692369992001;1012.1245122968579;1016.9237307201842;1035.6478797208986;22592.435189120402;27394.178174654793;28199.57700634492;28657.140285791458;28790.280242993675;1076.4886678827495;1079.1366906474946;1089.5696200001114;1090.4765712458066;1091.879893211746;12242.306692527629;12462.43919023646;12466.688332791571;12524.24854508714;12593.87406125927;225892;226698;227499;227989;228111;48399.75800120977;48491.51508484941;48648.75675621599;48923.266151007054;49028.26457603139;1401.9369128389044;1418.6311558994735;1433.7490939085826;1434.175349174229;1438.503716217903;22449.43876403103;22679.546409072056;22716.545669086194;22793;23054.884725576263;35537.28925421425;35839;35865.924022279374;35874.641253588175;35905.64094359023;184965;185809;186396;186406;186733;32062.83968580142;32395.51406728854;33350.33299334048;35034.64965350314;35316.47025294571;43291.134177315646;43302;43566.56433435625;43820;44016.77991610021;32484.83757581197;32605.18487037843;32628;32687;33221.50167747534;30780.692193078678;30864.911773213804;31301.68698313079;31692.683073168973;32294.83852580722;35363.46954795629;35528.822355888056;35731.285374292886;35929.48493605423;36038.27923441569;31019.534706979866;31050.378992419573;31119.533207002365;31363.37273254568;31491.527627085154;22882.65676014828;23554.411139721644;23648.290551283488;24041.398965026016;24212 05:11:33 INFO - PID 21028 | |2;jquery.html;543.3262754184811;552.7236381809096;557.8131326005788;559.4685049203258;573.7131434282859;21040.158393664253;21541.46146346342;21543.461413464673;21619;21758.12967481299;24308.878455607697;24706.87646561769;24833.75166248337;24839.37901552462;24862.378440538996;6513.250976137746;6683.598984060947;6684.699188536503;6705.832354191136;6706.530542861978;6458.192725909262;6639.601623902581;7116.715331386738;7120.28797120289;7120.608366539838;7135.3934915532245;7141.178764442104;7143.821404464878;7156.821079473003;7173.784786456415;7126.358627723504;7132.215456299803;7133.8216544586285;7136.500444968854;7142.7857164285415;6981.82545436363;7008.789736307868;7017.82455438613;7020.438364930793;7025.789226323167;24322.756772432225;24435.26694199177;24517;26830.195094147206;30596.69403305983;2004.2083377065978;2064.143380497092;2065.473304307407;2066.039291729338;2067.0284966065924;20189.697154542693;20821.063052162688;20948.895255523625;21034.368968930958;21093.367198984055;2530.177692250026;2541.885615147323;2614.790816734657;2618.2930608735724;2625.12058460416;133302;136525;137007.999999999;137137.999999999;137221.3138934299;19311.613767724568;20147.597048058957;21434.78565214359;21635.67546486804;21793.67309490359;41709.1658166835;41797.79101104475;41838.58161418408;41859.372109418386;41994.58005419968;10718.41048742311;10878.129749620011;10917.56329746817;10929.453527323602;11118.277311974696;7923.801904952422;8878.689245876363;8903.777405564913;8965.731028069169;9034.367594268339;9324.487153206503;10904.236703430684;11155.386453745123;11332.376719280519;11388.216948881489;3592.0192878856933;3683.6738774041323;3978.5822488638278;4109.445224894601;4158.272302347077;913.7578541686458;930.7719608695913;939.4081728510994;971.9271054670928;983.090641156927;19052.237910483425;19164.904175479034;19505.90247048784;19675.11461984214;22574.774252257597;1006.7231511334442;1024.288119756762;1029.2949329709172;1040.9375437473775;1048.208602505119;10787.244892857425;10821.322694982291;11096;11266.774664506827;12682.746345073047;483453;493253;501308;517073;518787;110724;110811;111096;113916.43041784904;114196;1190.3869507203797;1199.772043311768;1228.3060071059822;1228.7173949991372;1231.5566396097397;21163.576728465036;21247.681284780432;21254.46863828417;21306.467338316044;21324.360269191948;56483;58532.12201816891;59114.40885591089;59613;59616.70191649014;69771.30228697648;72156;72504;73749.26250737424;73987.26012739804;14234.288285585886;14359.353829077507;14385.064970776866;14451.349689263798;14473;22116.33650990473;22317.330480085624;22418.66372004454;22476.66285005759;22529.549409012056;17607.735883961494;17709.468715938543;17825.821741782416;17992.640147196722;18036.36872709449;17060.146992650567;17095.316187352368;17173.141342933053;17434.128293585523;17445.82554174493;18632.81367186365;19114;19162.329318473785;19208;19586.804131959067;14211.644708881948;14244;14386.136831790127;14497.492587759378;14502.492412765503;18386.448406547825;18415.53961150982;18468;18532.166052527937;18549.35077272343 05:11:33 INFO - PID 21028 | |3;mootools.html;1259.2318685601783;1290.2516540406561;1304.9347532623367;1320.8348956380453;1325.3307079924643;14246.857531424695;14636.268186590682;14867.479638212664;14886.404543818262;14893.25533723315;14986.475473358434;15183.468578599752;15218.771718424205;15248.542543723679;15267.77098343523;2690.4350086481863;2735.86320683966;2737.055715778063;2737.9178624641295;2738.5070687276284;2564.7050589182204;2622.514834755567;2659.0294542491947;2664.2806442260576;2664.440467501827;2657.5349313870092;2669.3326668332916;2680.142354446578;2697.4470233602165;2698.9325266868386;2632.8946842126293;2633.7366263373606;2649.774769144625;2653.62849201111;2659.3750468639855;2601.9349516262055;2622.396848724785;2641.366072142672;2642.960355594668;2643.458091091341;14109.082909610843;14446.061006034679;14448.638784030483;14461.927690361586;14473.565793026226;7426.331630153258;7651;7669.348105411047;7674.386049116058;7676.846463070708;5167.48325167484;5255.86860328491;5318.680879147263;5408.756605952742;5413.783448662049;1837.0906401331426;1872.953176170593;1888.282452667991;1908.7709474862966;1939.7575303087115;24906.37734056645;25151;25316;25319.240422787345;25324.746752532606;6315.115883776275;6432.13166222554;6499.5775274607;6575.309592492815;6602.405783479414;16430.83569164317;16440.095794731405;16440.424585139463;16453.83546164547;16463.094529800976;7321.194668586451;7533.434992375484;7557.735479258199;7586.355159811367;7597.202293759186;5634.971825140848;5809.593328466966;6057.091436284592;6081.087836824423;6996.405305549085;5062.620303477254;5175;5251.842444726664;5274.604404669665;5275.868103297448;1385.9168449893039;1401.8457969623332;1414.2645824171373;1423.0678905317034;1424.0530047518491;828.958552072394;845.1970627903517;849.5964416901923;855.1918437076903;871.9956400218085;7066.646667666699;7181.856362872818;7190.892136617955;7200.675969581382;7251.274872512707;678.7692184657142;696.1472196559174;919.1911118215927;963.3016063354153;968.9612415503301;4604.953950460519;4856;4859.271109333557;5010.473900240495;5114.87212819675;20463.590728185354;20937.79062209389;21233.575328493345;21399.89300053521;21491.78508214929;20810.89594552048;21048.684269735968;21097.89451052735;21183.470413239484;21248.25631102904;10542.26204165701;11182.552697891993;11191.72020699489;11400.258983166068;11415.714607134889;8397.874031889649;8573.871391929251;8660.610272537624;8813.074627164055;8864.246539044254;18039.508756196912;18083;18229.45311640653;18317.267309307477;18513.90743046276;18473;18641.44075677732;18801.247950081844;18836.905815470836;19065.332713354965;11761.588344407905;12059;12064.155509114278;12175.573854915036;12209.267443953391;13407.32963351809;13462.124961877447;13625.114367566077;13758;13764.105333153313;10673.146148308271;10702.357858528514;10723.088537474401;10729.892701073202;10916.508757106109;10470.790584188122;11018.889811102106;11036.779264414827;11179.66461006171;11283.097352211968;9551.474668892999;9552.522373881417;9571.808563828545;9670.226381889574;9698.85451718239;6924.723011079702;6996.720131194696;7083.185433675193;7143.999840022505;7190.137183538009;7094.751683691046;7112.253213412517;7139.892901606584;7174.174969878531;7238.855222895408 05:11:33 INFO - PID 21028 | |4;prototype.html;496.55007378454906;497.06995543169967;514.0900605927507;525.3905469655201;527.3618921105463;6368.52236082294;6413.8717225655455;6422.935770642298;6436.292007879129;6503.252126005499;6096.817095487131;6208.192934918454;6279.215098112736;6305.2433707955;6308.9684551577175;5077.41609714882;5085.771140298677;5091.541761241488;5104.974475127619;5113.718745468997;5054.797808087672;5060.2409638554145;5085.898282034357;5111.974440127813;5121.641485096045;4844.15227335215;4968.950310496885;5038.773255203525;5047.141985862394;5067.822626208083;5049.141645920203;5066;5066.569341605968;5088.821891233789;5091.490850914916;4479.39528163697;4487.708298960557;4548.8862778430475;4552.726836389827;4557.817687292504;6316.210473690789;6323.367663233686;6351.555391122623;6355.364463553654;6360.55476116672;5793.333766616851;5794.82615521535;5823.767049318022;5830.3586605473365;5833.300003999505;6628.138342015555;6641.634710090943;6751.459883209332;6778.864422711518;6804.795856124275;4857.73282469464;4867.196912509432;4897.436794768611;4898.191798353268;4923.7784299706245;7077.221505634376;7110.57336559808;7157.57054576727;7182.174049984266;7195.136583609948;5104.897902041938;5111.46329635383;5122.948770512322;5127.128388174019;5151.922721159186;5123.38707259293;5136.640435169502;5151.175811870083;5187.221916712523;5207.869803254949;5850.151727999416;5854.502367298742;5857.970710146507;5868.4424979626865;5885.293764748258;6785.796426107225;6810.182778066664;6821.795346139525;6863.416609588141;6882.174139103339;1669.991650041742;1707.9829201708073;1716.2191203002603;1757.261949981011;1763.7618921445442;1212.1817773003188;1235.3329202230875;1247.6444213399138;1253.8119282107757;1257.9747505782757;5810.80405776821;5820.970895145497;5874.823755287348;5892;5897.203877476486;6785.830354241183;6823.38589526945;6854.840725171555;6865.9313406866295;6895.000674706418;5820;5945.910811337833;5977.701114944235;5998.760049598054;6010.338862725097;3457.75795694299;3635.636436356343;3645.8177091145867;3654.8538058477893;3690.5940346561856;6638.634875081821;6643.548822289078;6659.500537459709;6661.566998145105;6672.366125218096;7025.437964962893;7105.538140020882;7114.92885071153;7123.572585644878;7146.3568278855155;6703.497237707289;6738.225104113089;6772.679327531084;6787.423069039187;6793.150856142982;6796.150481189851;6816.48876334267;6969.965150174216;6991.860162796817;6999.195092564217;6732.360425759545;6824.454043676593;6826.180858297036;6851.828704282233;6853.348931851466;6528.967355163194;6556.967340124576;6677.732890684319;6702.564333318319;6712.39588437043;5020.748962551931;5029.321041659476;5045.571126454146;5080.7205603692155;5092.159793634009;6638.668066596554;6667.8666426670225;6713.5089753328975;6754.677006715608;6838.589684618939;6464.935350646433;6552.410283074546;6623.105880706235;6651.567648102858;6683.498737594797;6647.7008534617025;6665.166854143232;6697.296783837627;6715.93284067153;6739.809561892503;6384.808455746335;6385.4355682857995;6517;6539.149910511794;6562;6451.70967306463;6470.547061705635;6567.540272180901;6610.206775187008;6668.399844014062;6432.774852880127;6432.903506447501;6467.159269294962;6489.3510648934725;6574.425574425574 05:11:33 INFO - PID 21028 | |5;yui.html;778.4823092643392;803.8697451752856;818.0714888601435;846.3356265331715;889.4752096263207;17626;18133.818661813395;18160.81839181608;18165.455036348896;21389.67915481269;20536;21201;21205.257815976445;21211.787882121174;21269;894.7315805258413;908.4322298563397;912.1972664055622;913.6071489259616;920.8158368326328;880.8458519759049;955.4267439536292;956.0726095687207;956.9330146889721;960.1310813713566;1011.5650270383769;1015.6039144733522;1015.6089905386406;1015.9746006349864;1016.0246163682837;996.6362277768635;996.6661168508558;996.7209181429175;997.286939838012;1000.0999100809257;739.8002539314355;740.9629518524106;744.426791370647;744.4974642116549;744.5718711740717;18715.344962926232;19139;19225.13486893093;19327.323543675902;20060.49848753778;20727.481812954647;20801;21119.5776084479;21212.363629091153;21214.25750098739;16918.561922236626;17407.30370785167;17520.21159047846;17522.47432577012;17656.558586035324;3736.532933383327;3750.2499500099816;3771.585125636178;3772.4152756322815;3772.9245415091546;127243;127601;131935.34032329873;134332.32833835768;134914.3254283732;15599.376024958874;15658.373665053497;15833.287502062434;16045.598860028593;16219.675606488041;28449.85775071153;28593;28717.712822871505;28831.56752648712;29327.120186394015;7411.962940185264;7815.218478152253;8470.364722645827;8528.360372972053;8559.74320770378;4234.428352172417;4289.721168124124;4294.441722576107;4303.4190384298345;4315.503717072514;5003;5134.691918484903;5140.280360749498;5164.096283150434;5209.01028804526;971.5627967414691;983.8032393521324;993.2798720927415;995.5121990224883;996.4220751963844;633.5438484291395;678.2437582096026;689.334791925784;698.4168219536743;699.6117154978933;11085.778284434266;11577.536898524133;11694.415279236004;11712.297262164297;11820.822687659693;637.4326849104208;655.4297760948006;666.2305037681485;687.71116131225;698.9685464154126;5442.673439593637;5592.5805564582815;5729.369769325371;5785.88428231433;5821.4979817610665;105677.47161264298;107508.46245768722;112277;112379;114336;96369;98710.5064474673;99529;100516.49741751244;103239.48380258051;88562.55718721494;90603.54698226598;91053.54473227724;91799.5410022959;93588.53205733928;10266.538005789607;10362.119219866094;10376.792464150825;10634.946825265824;12934.159279646792;40823.38764918469;42142.36786448144;42282.36576451417;42535.361969569865;42798.57201427946;67785.66107169433;68849;70487;71862.28137718556;72192.63903680448;17576.56058598545;17858.46424607264;18201.81798182001;18529.166187521863;18617.813821861608;17952.461426157235;18047;18267.725984109984;19103.14035868417;19245.230190792772;17764.644707106043;18177.09114544294;18180.818191818442;18616;18842.623147537248;18120.456386308433;18527.81472185315;18707.625847483247;18981.715274271173;19593;16984.235709393353;17016.149192540077;17130.571735706708;17328.13359332003;17763.555911102325;13324.200547967153;13401.463941442233;13518.459261629425;13678;13921.025528212927;14070.155790652594;14367.640808979859;14526.636834079232;14713.852861471249;15066.472673456376 05:11:33 INFO - PID 21028 | __end_tp_report 05:11:33 INFO - PID 21028 | __start_cc_report 05:11:33 INFO - PID 21028 | _x_x_mozilla_cycle_collect,386 05:11:33 INFO - PID 21028 | __end_cc_report 05:11:33 INFO - PID 21028 | __startTimestamp1503663093589__endTimestamp 05:11:33 INFO - PID 21028 | 05:11:33 INFO - PID 21028 | ------- Summary: start ------- 05:11:33 INFO - PID 21028 | Number of tests: 6 05:11:33 INFO - PID 21028 | 05:11:33 INFO - PID 21028 | [#0] dojo.html Cycles:165 Average:6294.79 Median:6535.80 stddev:739.67 (11.3%) stddev-sans-first:703.14 05:11:33 INFO - PID 21028 | Values: 3281.0 3286.7 3307.2 3312.8 3317.9 6335.3 6366.8 6402.9 6420.1 6449.8 6243.4 6277.2 6321.5 6325.6 6360.8 5754.7 5824.2 5839.2 5858.1 5865.9 5904.9 5919.9 5947.0 5947.1 5971.9 5876.7 5905.2 5911.3 5994.5 6012.4 5645.6 5792.7 5799.8 5915.7 5950.9 5802.9 5806.9 5864.5 5909.2 5913.2 6183.1 6261.9 6317.2 6351.3 6433.1 6615.7 6724.3 6729.9 6757.9 6892.9 6915.5 6922.1 6929.0 6933.1 6971.6 6381.0 6384.0 6466.9 6476.2 6478.4 6882.3 7022.6 7047.5 7110.0 7127.4 6612.6 6685.3 6691.0 6696.9 6697.1 6771.4 6776.9 6784.9 6790.0 6854.7 6838.5 6859.1 6879.3 6900.4 6923.2 6778.1 6815.2 6888.2 6889.7 6892.6 5353.4 5354.5 5421.9 5448.7 5449.7 4623.3 4669.3 4669.3 4728.1 4809.4 5984.9 5985.9 5997.0 6009.5 6009.7 6839.1 6905.6 6915.3 6924.5 6932.2 5940.2 5958.4 6028.7 6034.5 6038.3 5764.0 5844.5 5853.6 5904.9 5943.3 6672.9 6698.5 6706.7 6708.1 6714.3 6849.6 6854.0 6940.2 6942.0 6946.7 6596.8 6611.0 6703.3 6703.4 6714.8 6570.5 6665.6 6699.4 6717.1 6790.4 6813.2 6894.5 6899.3 6910.7 6915.3 6611.2 6618.1 6624.4 6694.1 6708.4 6638.9 6668.0 6700.8 6707.2 6721.0 6327.0 6443.5 6454.6 6538.2 6552.5 6696.8 6707.4 6724.4 6739.2 6781.2 6358.5 6370.8 6426.2 6429.6 6535.8 05:11:33 INFO - PID 21028 | 05:11:33 INFO - PID 21028 | [#1] ext.html Cycles:180 Average:36310.24 Median:3439.04 stddev:51880.22 (1508.6%) stddev-sans-first:51957.19 05:11:33 INFO - PID 21028 | Values: 787.1 838.5 865.2 866.7 1098.8 28989.9 29686.9 37390.3 39022.8 39036.4 39749.8 39939.6 40128.8 40153.6 40201.0 7579.4 7621.7 7755.2 7776.5 7791.7 7566.4 7678.3 7680.6 7683.0 7686.2 7681.8 7742.5 7747.1 7749.5 7750.5 7656.3 7677.0 7691.2 7705.7 7708.8 7587.8 7614.7 7617.8 7625.2 7632.7 35209.6 35433.5 35445.3 35476.1 35541.3 3419.6 3458.4 3458.6 3460.9 3475.5 29923.3 29968.0 29974.1 29981.9 30026.4 5131.2 5134.3 5177.9 5223.5 5229.1 183943.0 185350.0 185541.0 185656.0 185991.0 45999.3 46039.8 46109.5 46195.8 46216.0 47565.9 47610.3 47643.0 47646.5 47659.3 14140.6 14353.4 14369.5 14373.7 14381.4 10204.6 10338.3 10399.2 11524.3 11749.0 13520.7 13526.2 13527.9 13561.7 13684.6 5562.5 5624.9 5644.4 5664.3 5679.1 989.1 1005.9 1012.1 1016.9 1035.6 22592.4 27394.2 28199.6 28657.1 28790.3 1076.5 1079.1 1089.6 1090.5 1091.9 12242.3 12462.4 12466.7 12524.2 12593.9 225892.0 226698.0 227499.0 227989.0 228111.0 48399.8 48491.5 48648.8 48923.3 49028.3 1401.9 1418.6 1433.7 1434.2 1438.5 22449.4 22679.5 22716.5 22793.0 23054.9 35537.3 35839.0 35865.9 35874.6 35905.6 184965.0 185809.0 186396.0 186406.0 186733.0 32062.8 32395.5 33350.3 35034.6 35316.5 43291.1 43302.0 43566.6 43820.0 44016.8 32484.8 32605.2 32628.0 32687.0 33221.5 30780.7 30864.9 31301.7 31692.7 32294.8 35363.5 35528.8 35731.3 35929.5 36038.3 31019.5 31050.4 31119.5 31363.4 31491.5 22882.7 23554.4 23648.3 24041.4 24212.0 05:11:33 INFO - PID 21028 | 05:11:33 INFO - PID 21028 | [#2] jquery.html Cycles:180 Average:35893.00 Median:21696.90 stddev:84333.15 (388.7%) stddev-sans-first:84527.96 05:11:33 INFO - PID 21028 | Values: 543.3 552.7 557.8 559.5 573.7 21040.2 21541.5 21543.5 21619.0 21758.1 24308.9 24706.9 24833.8 24839.4 24862.4 6513.3 6683.6 6684.7 6705.8 6706.5 6458.2 6639.6 7116.7 7120.3 7120.6 7135.4 7141.2 7143.8 7156.8 7173.8 7126.4 7132.2 7133.8 7136.5 7142.8 6981.8 7008.8 7017.8 7020.4 7025.8 24322.8 24435.3 24517.0 26830.2 30596.7 2004.2 2064.1 2065.5 2066.0 2067.0 20189.7 20821.1 20948.9 21034.4 21093.4 2530.2 2541.9 2614.8 2618.3 2625.1 133302.0 136525.0 137008.0 137138.0 137221.3 19311.6 20147.6 21434.8 21635.7 21793.7 41709.2 41797.8 41838.6 41859.4 41994.6 10718.4 10878.1 10917.6 10929.5 11118.3 7923.8 8878.7 8903.8 8965.7 9034.4 9324.5 10904.2 11155.4 11332.4 11388.2 3592.0 3683.7 3978.6 4109.4 4158.3 913.8 930.8 939.4 971.9 983.1 19052.2 19164.9 19505.9 19675.1 22574.8 1006.7 1024.3 1029.3 1040.9 1048.2 10787.2 10821.3 11096.0 11266.8 12682.7 483453.0 493253.0 501308.0 517073.0 518787.0 110724.0 110811.0 111096.0 113916.4 114196.0 1190.4 1199.8 1228.3 1228.7 1231.6 21163.6 21247.7 21254.5 21306.5 21324.4 56483.0 58532.1 59114.4 59613.0 59616.7 69771.3 72156.0 72504.0 73749.3 73987.3 14234.3 14359.4 14385.1 14451.3 14473.0 22116.3 22317.3 22418.7 22476.7 22529.5 17607.7 17709.5 17825.8 17992.6 18036.4 17060.1 17095.3 17173.1 17434.1 17445.8 18632.8 19114.0 19162.3 19208.0 19586.8 14211.6 14244.0 14386.1 14497.5 14502.5 18386.4 18415.5 18468.0 18532.2 18549.4 05:11:33 INFO - PID 21028 | 05:11:33 INFO - PID 21028 | [#3] mootools.html Cycles:180 Average:9058.09 Median:2637.55 stddev:6493.49 (246.2%) stddev-sans-first:6485.26 05:11:33 INFO - PID 21028 | Values: 1259.2 1290.3 1304.9 1320.8 1325.3 14246.9 14636.3 14867.5 14886.4 14893.3 14986.5 15183.5 15218.8 15248.5 15267.8 2690.4 2735.9 2737.1 2737.9 2738.5 2564.7 2622.5 2659.0 2664.3 2664.4 2657.5 2669.3 2680.1 2697.4 2698.9 2632.9 2633.7 2649.8 2653.6 2659.4 2601.9 2622.4 2641.4 2643.0 2643.5 14109.1 14446.1 14448.6 14461.9 14473.6 7426.3 7651.0 7669.3 7674.4 7676.8 5167.5 5255.9 5318.7 5408.8 5413.8 1837.1 1873.0 1888.3 1908.8 1939.8 24906.4 25151.0 25316.0 25319.2 25324.7 6315.1 6432.1 6499.6 6575.3 6602.4 16430.8 16440.1 16440.4 16453.8 16463.1 7321.2 7533.4 7557.7 7586.4 7597.2 5635.0 5809.6 6057.1 6081.1 6996.4 5062.6 5175.0 5251.8 5274.6 5275.9 1385.9 1401.8 1414.3 1423.1 1424.1 829.0 845.2 849.6 855.2 872.0 7066.6 7181.9 7190.9 7200.7 7251.3 678.8 696.1 919.2 963.3 969.0 4605.0 4856.0 4859.3 5010.5 5114.9 20463.6 20937.8 21233.6 21399.9 21491.8 20810.9 21048.7 21097.9 21183.5 21248.3 10542.3 11182.6 11191.7 11400.3 11415.7 8397.9 8573.9 8660.6 8813.1 8864.2 18039.5 18083.0 18229.5 18317.3 18513.9 18473.0 18641.4 18801.2 18836.9 19065.3 11761.6 12059.0 12064.2 12175.6 12209.3 13407.3 13462.1 13625.1 13758.0 13764.1 10673.1 10702.4 10723.1 10729.9 10916.5 10470.8 11018.9 11036.8 11179.7 11283.1 9551.5 9552.5 9571.8 9670.2 9698.9 6924.7 6996.7 7083.2 7144.0 7190.1 7094.8 7112.3 7139.9 7174.2 7238.9 05:11:33 INFO - PID 21028 | 05:11:33 INFO - PID 21028 | [#4] prototype.html Cycles:180 Average:5626.17 Median:6319.79 stddev:1594.51 (25.2%) stddev-sans-first:1551.80 05:11:33 INFO - PID 21028 | Values: 496.6 497.1 514.1 525.4 527.4 6368.5 6413.9 6422.9 6436.3 6503.3 6096.8 6208.2 6279.2 6305.2 6309.0 5077.4 5085.8 5091.5 5105.0 5113.7 5054.8 5060.2 5085.9 5112.0 5121.6 4844.2 4969.0 5038.8 5047.1 5067.8 5049.1 5066.0 5066.6 5088.8 5091.5 4479.4 4487.7 4548.9 4552.7 4557.8 6316.2 6323.4 6351.6 6355.4 6360.6 5793.3 5794.8 5823.8 5830.4 5833.3 6628.1 6641.6 6751.5 6778.9 6804.8 4857.7 4867.2 4897.4 4898.2 4923.8 7077.2 7110.6 7157.6 7182.2 7195.1 5104.9 5111.5 5122.9 5127.1 5151.9 5123.4 5136.6 5151.2 5187.2 5207.9 5850.2 5854.5 5858.0 5868.4 5885.3 6785.8 6810.2 6821.8 6863.4 6882.2 1670.0 1708.0 1716.2 1757.3 1763.8 1212.2 1235.3 1247.6 1253.8 1258.0 5810.8 5821.0 5874.8 5892.0 5897.2 6785.8 6823.4 6854.8 6865.9 6895.0 5820.0 5945.9 5977.7 5998.8 6010.3 3457.8 3635.6 3645.8 3654.9 3690.6 6638.6 6643.5 6659.5 6661.6 6672.4 7025.4 7105.5 7114.9 7123.6 7146.4 6703.5 6738.2 6772.7 6787.4 6793.2 6796.2 6816.5 6970.0 6991.9 6999.2 6732.4 6824.5 6826.2 6851.8 6853.3 6529.0 6557.0 6677.7 6702.6 6712.4 5020.7 5029.3 5045.6 5080.7 5092.2 6638.7 6667.9 6713.5 6754.7 6838.6 6464.9 6552.4 6623.1 6651.6 6683.5 6647.7 6665.2 6697.3 6715.9 6739.8 6384.8 6385.4 6517.0 6539.1 6562.0 6451.7 6470.5 6567.5 6610.2 6668.4 6432.8 6432.9 6467.2 6489.4 6574.4 05:11:33 INFO - PID 21028 | 05:11:33 INFO - PID 21028 | [#5] yui.html Cycles:180 Average:23982.06 Median:21241.63 stddev:33069.60 (155.7%) stddev-sans-first:33116.47 05:11:33 INFO - PID 21028 | Values: 778.5 803.9 818.1 846.3 889.5 17626.0 18133.8 18160.8 18165.5 21389.7 20536.0 21201.0 21205.3 21211.8 21269.0 894.7 908.4 912.2 913.6 920.8 880.8 955.4 956.1 956.9 960.1 1011.6 1015.6 1015.6 1016.0 1016.0 996.6 996.7 996.7 997.3 1000.1 739.8 741.0 744.4 744.5 744.6 18715.3 19139.0 19225.1 19327.3 20060.5 20727.5 20801.0 21119.6 21212.4 21214.3 16918.6 17407.3 17520.2 17522.5 17656.6 3736.5 3750.2 3771.6 3772.4 3772.9 127243.0 127601.0 131935.3 134332.3 134914.3 15599.4 15658.4 15833.3 16045.6 16219.7 28449.9 28593.0 28717.7 28831.6 29327.1 7412.0 7815.2 8470.4 8528.4 8559.7 4234.4 4289.7 4294.4 4303.4 4315.5 5003.0 5134.7 5140.3 5164.1 5209.0 971.6 983.8 993.3 995.5 996.4 633.5 678.2 689.3 698.4 699.6 11085.8 11577.5 11694.4 11712.3 11820.8 637.4 655.4 666.2 687.7 699.0 5442.7 5592.6 5729.4 5785.9 5821.5 105677.5 107508.5 112277.0 112379.0 114336.0 96369.0 98710.5 99529.0 100516.5 103239.5 88562.6 90603.5 91053.5 91799.5 93588.5 10266.5 10362.1 10376.8 10634.9 12934.2 40823.4 42142.4 42282.4 42535.4 42798.6 67785.7 68849.0 70487.0 71862.3 72192.6 17576.6 17858.5 18201.8 18529.2 18617.8 17952.5 18047.0 18267.7 19103.1 19245.2 17764.6 18177.1 18180.8 18616.0 18842.6 18120.5 18527.8 18707.6 18981.7 19593.0 16984.2 17016.1 17130.6 17328.1 17763.6 13324.2 13401.5 13518.5 13678.0 13921.0 14070.2 14367.6 14526.6 14713.9 15066.5 05:11:33 INFO - PID 21028 | -------- Summary: end -------- 05:11:33 INFO - PID 21028 | 05:11:34 INFO - TEST-INFO | 21028: exit 0 05:11:34 INFO - TEST-OK | dromaeo_css | took 1112523ms 05:11:34 INFO - TEST-START | kraken 05:11:34 INFO - Initialising browser for kraken test... 05:11:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34066/getInfo.html -profile /tmp/tmpBAMsc_/profile 05:11:34 INFO - TEST-INFO | started process 21451 (/builds/slave/test/build/application/firefox/firefox http://localhost:34066/getInfo.html) 05:11:40 INFO - TEST-INFO | 21451: exit 0 05:11:40 INFO - Browser initialized. 05:11:40 INFO - Running cycle 1/1 for kraken test... 05:11:40 INFO - Using env: {'DISPLAY': ':0', 05:11:40 INFO - 'HOME': '/home/cltbld', 05:11:40 INFO - 'JSGC_DISABLE_POISONING': '1', 05:11:40 INFO - 'LANG': 'en_US.UTF-8', 05:11:40 INFO - 'LANGUAGE': 'en_US:en', 05:11:40 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 05:11:40 INFO - 'LOGNAME': 'cltbld', 05:11:40 INFO - 'MAIL': '/var/mail/cltbld', 05:11:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:11:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:11:40 INFO - 'MOZ_CRASHREPORTER': '1', 05:11:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:40 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 05:11:40 INFO - 'MOZ_NO_REMOTE': '1', 05:11:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:11:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:40 INFO - 'NO_EM_RESTART': '1', 05:11:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:40 INFO - 'PWD': '/builds/slave/test', 05:11:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:11:40 INFO - 'RUST_BACKTRACE': 'full', 05:11:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:11:40 INFO - 'SHELL': '/bin/bash', 05:11:40 INFO - 'SHLVL': '1', 05:11:40 INFO - 'STYLO_FORCE_ENABLED': '1', 05:11:40 INFO - 'TERM': 'linux', 05:11:40 INFO - 'TMOUT': '86400', 05:11:40 INFO - 'USER': 'cltbld', 05:11:40 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392', 05:11:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:40 INFO - '_': '/tools/buildbot/bin/python'} 05:11:40 INFO - TEST-INFO | started process 21640 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBAMsc_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:11:41 INFO - PID 21640 | 05:11:41 INFO - PID 21640 | (/builds/slave/test/build/application/firefox/firefox:21690): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:11:41 INFO - PID 21640 | 05:11:41 INFO - PID 21640 | 05:11:41 INFO - PID 21640 | (/builds/slave/test/build/application/firefox/firefox:21751): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:11:41 INFO - PID 21640 | 05:11:41 INFO - PID 21640 | __metrics Screen width/height:1600/1200 05:11:41 INFO - PID 21640 | colorDepth:24 05:11:41 INFO - PID 21640 | Browser inner width/height: 1024/768 05:11:41 INFO - PID 21640 | __metrics 05:11:51 INFO - PID 21640 | RSS: Main: 174915584 05:11:51 INFO - PID 21640 | 05:11:54 INFO - PID 21640 | Cycle 1(1): loaded http://localhost:34066/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34066/tests/kraken/driver.html?testName=audio-beat-detection) 05:11:55 INFO - PID 21640 | RSS: Main: 180699136 05:11:55 INFO - PID 21640 | 05:11:57 INFO - PID 21640 | Cycle 1(1): loaded http://localhost:34066/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34066/tests/kraken/driver.html?testName=audio-dft) 05:11:58 INFO - PID 21640 | RSS: Main: 180482048 05:11:58 INFO - PID 21640 | 05:12:01 INFO - PID 21640 | Cycle 1(1): loaded http://localhost:34066/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34066/tests/kraken/driver.html?testName=audio-fft) 05:12:02 INFO - PID 21640 | RSS: Main: 174288896 05:12:02 INFO - PID 21640 | 05:12:04 INFO - PID 21640 | Cycle 1(1): loaded http://localhost:34066/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34066/tests/kraken/driver.html?testName=audio-oscillator) 05:12:05 INFO - PID 21640 | RSS: Main: 174129152 05:12:05 INFO - PID 21640 | 05:12:07 INFO - PID 21640 | Cycle 1(1): loaded http://localhost:34066/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34066/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:12:07 INFO - PID 21640 | RSS: Main: 174772224 05:12:07 INFO - PID 21640 | 05:12:11 INFO - PID 21640 | Cycle 1(1): loaded http://localhost:34066/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34066/tests/kraken/driver.html?testName=imaging-darkroom) 05:12:11 INFO - PID 21640 | RSS: Main: 175607808 05:12:11 INFO - PID 21640 | 05:12:15 INFO - PID 21640 | Cycle 1(1): loaded http://localhost:34066/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34066/tests/kraken/driver.html?testName=imaging-desaturate) 05:12:15 INFO - PID 21640 | RSS: Main: 175153152 05:12:15 INFO - PID 21640 | 05:12:18 INFO - PID 21640 | Cycle 1(1): loaded http://localhost:34066/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34066/tests/kraken/driver.html?testName=json-parse-financial) 05:12:19 INFO - PID 21640 | RSS: Main: 175833088 05:12:19 INFO - PID 21640 | 05:12:21 INFO - PID 21640 | Cycle 1(1): loaded http://localhost:34066/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34066/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:12:21 INFO - PID 21640 | RSS: Main: 176365568 05:12:21 INFO - PID 21640 | 05:12:23 INFO - PID 21640 | Cycle 1(1): loaded http://localhost:34066/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34066/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:12:24 INFO - PID 21640 | RSS: Main: 176287744 05:12:24 INFO - PID 21640 | 05:12:26 INFO - PID 21640 | Cycle 1(1): loaded http://localhost:34066/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34066/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:12:27 INFO - PID 21640 | RSS: Main: 177180672 05:12:27 INFO - PID 21640 | 05:12:29 INFO - PID 21640 | Cycle 1(1): loaded http://localhost:34066/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34066/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:12:30 INFO - PID 21640 | RSS: Main: 176865280 05:12:30 INFO - PID 21640 | 05:12:33 INFO - PID 21640 | Cycle 1(1): loaded http://localhost:34066/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34066/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:12:34 INFO - PID 21640 | RSS: Main: 176828416 05:12:34 INFO - PID 21640 | 05:12:35 INFO - PID 21640 | Cycle 1(1): loaded http://localhost:34066/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34066/tests/kraken/driver.html?testName=ai-astar) 05:12:36 INFO - PID 21640 | RSS: Main: 178360320 05:12:36 INFO - PID 21640 | 05:12:36 INFO - PID 21640 | __start_tp_report 05:12:36 INFO - PID 21640 | _x_x_mozilla_page_load 05:12:36 INFO - PID 21640 | _x_x_mozilla_page_load_details 05:12:36 INFO - PID 21640 | |i|pagename|runs| 05:12:36 INFO - PID 21640 | |0;ai-astar;97;108;99;107;112;108;114;97;108;107 05:12:36 INFO - PID 21640 | |1;audio-beat-detection;146;119;150;177;118;166;145;122;175;117 05:12:36 INFO - PID 21640 | |2;audio-dft;174;161;163;161;162;164;181;173;163;162 05:12:36 INFO - PID 21640 | |3;audio-fft;77;74;73;175;85;82;73;82;163;82 05:12:36 INFO - PID 21640 | |4;audio-oscillator;81;82;129;79;78;83;128;80;78;125 05:12:36 INFO - PID 21640 | |5;imaging-gaussian-blur;119;114;114;113;114;118;113;114;118;117 05:12:36 INFO - PID 21640 | |6;imaging-darkroom;148;150;148;149;147;147;150;147;148;149 05:12:36 INFO - PID 21640 | |7;imaging-desaturate;99;94;94;93;94;96;94;94;101;95 05:12:36 INFO - PID 21640 | |8;json-parse-financial;60;52;51;52;55;51;52;52;52;52 05:12:36 INFO - PID 21640 | |9;json-stringify-tinderbox;56;55;56;55;55;55;57;56;55;56 05:12:36 INFO - PID 21640 | |10;stanford-crypto-aes;76;82;75;75;76;74;75;78;74;75 05:12:36 INFO - PID 21640 | |11;stanford-crypto-ccm;128;126;117;116;128;120;118;125;120;152 05:12:36 INFO - PID 21640 | |12;stanford-crypto-pbkdf2;181;179;177;169;172;175;170;176;170;171 05:12:36 INFO - PID 21640 | |13;stanford-crypto-sha256-iterative;67;73;74;66;67;64;62;69;65;63 05:12:36 INFO - PID 21640 | __end_tp_report 05:12:36 INFO - PID 21640 | __start_cc_report 05:12:36 INFO - PID 21640 | _x_x_mozilla_cycle_collect,960 05:12:36 INFO - PID 21640 | __end_cc_report 05:12:36 INFO - PID 21640 | __startTimestamp1503663156172__endTimestamp 05:12:36 INFO - PID 21640 | 05:12:36 INFO - PID 21640 | ------- Summary: start ------- 05:12:36 INFO - PID 21640 | Number of tests: 14 05:12:36 INFO - PID 21640 | 05:12:36 INFO - PID 21640 | [#0] ai-astar Cycles:10 Average:105.70 Median:113.00 stddev:6.00 (5.3%) stddev-sans-first:5.48 05:12:36 INFO - PID 21640 | Values: 97.0 108.0 99.0 107.0 112.0 108.0 114.0 97.0 108.0 107.0 05:12:36 INFO - PID 21640 | 05:12:36 INFO - PID 21640 | [#1] audio-beat-detection Cycles:10 Average:143.50 Median:148.00 stddev:23.77 (16.1%) stddev-sans-first:25.20 05:12:36 INFO - PID 21640 | Values: 146.0 119.0 150.0 177.0 118.0 166.0 145.0 122.0 175.0 117.0 05:12:36 INFO - PID 21640 | 05:12:36 INFO - PID 21640 | [#2] audio-dft Cycles:10 Average:166.40 Median:163.50 stddev:7.00 (4.3%) stddev-sans-first:6.86 05:12:36 INFO - PID 21640 | Values: 174.0 161.0 163.0 161.0 162.0 164.0 181.0 173.0 163.0 162.0 05:12:36 INFO - PID 21640 | 05:12:36 INFO - PID 21640 | [#3] audio-fft Cycles:10 Average:96.60 Median:79.50 stddev:38.50 (48.4%) stddev-sans-first:40.17 05:12:36 INFO - PID 21640 | Values: 77.0 74.0 73.0 175.0 85.0 82.0 73.0 82.0 163.0 82.0 05:12:36 INFO - PID 21640 | 05:12:36 INFO - PID 21640 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:79.50 stddev:22.87 (28.8%) stddev-sans-first:23.75 05:12:36 INFO - PID 21640 | Values: 81.0 82.0 129.0 79.0 78.0 83.0 128.0 80.0 78.0 125.0 05:12:36 INFO - PID 21640 | 05:12:36 INFO - PID 21640 | [#5] imaging-gaussian-blur Cycles:10 Average:115.40 Median:115.50 stddev:2.32 (2.0%) stddev-sans-first:2.06 05:12:36 INFO - PID 21640 | Values: 119.0 114.0 114.0 113.0 114.0 118.0 113.0 114.0 118.0 117.0 05:12:36 INFO - PID 21640 | 05:12:36 INFO - PID 21640 | [#6] imaging-darkroom Cycles:10 Average:148.30 Median:148.50 stddev:1.16 (0.8%) stddev-sans-first:1.22 05:12:36 INFO - PID 21640 | Values: 148.0 150.0 148.0 149.0 147.0 147.0 150.0 147.0 148.0 149.0 05:12:36 INFO - PID 21640 | 05:12:36 INFO - PID 21640 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:94.00 stddev:2.59 (2.8%) stddev-sans-first:2.40 05:12:36 INFO - PID 21640 | Values: 99.0 94.0 94.0 93.0 94.0 96.0 94.0 94.0 101.0 95.0 05:12:36 INFO - PID 21640 | 05:12:36 INFO - PID 21640 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:52.00 stddev:2.73 (5.2%) stddev-sans-first:1.17 05:12:36 INFO - PID 21640 | Values: 60.0 52.0 51.0 52.0 55.0 51.0 52.0 52.0 52.0 52.0 05:12:36 INFO - PID 21640 | 05:12:36 INFO - PID 21640 | [#9] json-stringify-tinderbox Cycles:10 Average:55.60 Median:56.00 stddev:0.70 (1.2%) stddev-sans-first:0.73 05:12:36 INFO - PID 21640 | Values: 56.0 55.0 56.0 55.0 55.0 55.0 57.0 56.0 55.0 56.0 05:12:36 INFO - PID 21640 | 05:12:36 INFO - PID 21640 | [#10] stanford-crypto-aes Cycles:10 Average:76.00 Median:75.50 stddev:2.40 (3.2%) stddev-sans-first:2.55 05:12:36 INFO - PID 21640 | Values: 76.0 82.0 75.0 75.0 76.0 74.0 75.0 78.0 74.0 75.0 05:12:36 INFO - PID 21640 | 05:12:36 INFO - PID 21640 | [#11] stanford-crypto-ccm Cycles:10 Average:125.00 Median:125.50 stddev:10.50 (8.4%) stddev-sans-first:11.08 05:12:36 INFO - PID 21640 | Values: 128.0 126.0 117.0 116.0 128.0 120.0 118.0 125.0 120.0 152.0 05:12:36 INFO - PID 21640 | 05:12:36 INFO - PID 21640 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.00 Median:175.50 stddev:4.19 (2.4%) stddev-sans-first:3.60 05:12:36 INFO - PID 21640 | Values: 181.0 179.0 177.0 169.0 172.0 175.0 170.0 176.0 170.0 171.0 05:12:36 INFO - PID 21640 | 05:12:36 INFO - PID 21640 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:4.00 (6.0%) stddev-sans-first:4.24 05:12:36 INFO - PID 21640 | Values: 67.0 73.0 74.0 66.0 67.0 64.0 62.0 69.0 65.0 63.0 05:12:36 INFO - PID 21640 | -------- Summary: end -------- 05:12:36 INFO - PID 21640 | 05:12:36 INFO - PID 21640 | 05:12:36 INFO - PID 21640 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150081,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 05:12:36 INFO - PID 21640 | 05:12:36 INFO - PID 21640 | 05:12:36 INFO - PID 21640 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 05:12:36 INFO - PID 21640 | 05:12:36 INFO - PID 21640 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 05:12:37 INFO - TEST-INFO | 21640: exit 0 05:12:37 INFO - TEST-OK | kraken | took 62856ms 05:12:37 INFO - SUITE-END | took 1175s 05:12:37 INFO - Completed test suite (00:19:35) 05:12:37 INFO - javascript benchmark 05:12:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8563.327496267479, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3280.983595082025, 3286.704196622302, 3307.1732066983245, 3312.7515436342283, 3317.9336413271735, 6335.334789847069, 6366.808995730135, 6402.871942561146, 6420.06908998195, 6449.806505804821, 6243.375662433755, 6277.2153480814895, 6321.462675672573, 6325.557210995232, 6360.777372791953, 5754.712264386785, 5824.213731146294, 5839.153322768196, 5858.12128180772, 5865.85335366615, 5904.852378690524, 5919.911201331984, 5947.048472244442, 5947.137665038566, 5971.850703732398, 5876.676782776945, 5905.2323197984415, 5911.320198177222, 5994.490468310199, 6012.398760123996, 5645.604807663465, 5792.710364481801, 5799.797007104773, 5915.733791979371, 5950.8809823803285, 5802.908875037078, 5806.854828629318, 5864.501517371029, 5909.202257695242, 5913.201717768132, 6183.134361189437, 6261.937380626181, 6317.210348706411, 6351.333110023474, 6433.099366088752, 6615.702293396808, 6724.260331363546, 6729.899051514231, 6757.898631520531, 6892.862142757167, 6915.51591388603, 6922.06552115463, 6929.029935808991, 6933.064036355128, 6971.581705097659, 6381.042843573501, 6384.0, 6466.902996455057, 6476.222853257592, 6478.384553467366, 6882.277360877036, 7022.6488675566015, 7047.471439642048, 7110.0401445804155, 7127.358537731526, 6612.636305003271, 6685.331466853276, 6691.029800678874, 6696.8995465068065, 6697.062411262427, 6771.424428923597, 6776.898346524806, 6784.93215067843, 6790.049393084873, 6854.691538880663, 6838.48711346651, 6859.108315918998, 6879.277675844064, 6900.447964162855, 6923.2038315594355, 6778.051072849805, 6815.216250131299, 6888.207856096613, 6889.689963951634, 6892.620905850226, 5353.38436079848, 5354.491323324278, 5421.918671219935, 5448.700321482277, 5449.700266485382, 4623.283391074388, 4669.252919532926, 4669.34629151919, 4728.0543891221205, 4809.350737650372, 5984.910226346608, 5985.850353741192, 5997.010493268649, 6009.519238460913, 6009.699515024231, 6839.076724642209, 6905.585664860125, 6915.273896240822, 6924.549904256207, 6932.23745388007, 5940.227770389822, 5958.404159584007, 6028.698565071817, 6034.456898879122, 6038.30559485656, 5763.971180144156, 5844.503217226498, 5853.590248682552, 5904.911426328523, 5943.3462319144855, 6672.933270667231, 6698.497612679069, 6706.664666766642, 6708.094407255056, 6714.294999025032, 6849.554778939353, 6853.965730171317, 6940.201876784033, 6941.965290173517, 6946.65266736651, 6596.769113081211, 6610.9669451652435, 6703.29615390377, 6703.396694297537, 6714.83212919681, 6570.47436205112, 6665.566738162004, 6699.43054840344, 6717.126773519607, 6790.354916282945, 6813.182418109858, 6894.517383783087, 6899.344562266577, 6910.723571057101, 6915.273896240822, 6611.173603299588, 6618.106555614931, 6624.403803657694, 6694.0628312037325, 6708.396244338033, 6638.867222655616, 6667.966660166862, 6700.765473208415, 6707.228668702966, 6720.966395167993, 6327.0, 6443.548951573345, 6454.580452270588, 6538.1500404947055, 6552.541322107407, 6696.83257918556, 6707.3628005339415, 6724.394804467622, 6739.157605299338, 6781.220159681699, 6358.459530939737, 6370.77702280418, 6426.196725409324, 6429.646369449725, 6535.803925882231], "value": 6240.173912433468, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [787.1105650614804, 838.4969018588847, 865.1607940297911, 866.726981000985, 1098.8296813993834, 28989.855050724742, 29686.851565742167, 37390.25219495608, 39022.8048859756, 39036.41445378322, 39749.8012509937, 39939.60060399395, 40128.799356003256, 40153.598464015355, 40201.0, 7579.393648508134, 7621.695132194719, 7755.185705500926, 7776.4945278557, 7791.727289544867, 7566.432517561205, 7678.308952194301, 7680.615969201517, 7683.039620047494, 7686.231376862297, 7681.769546913573, 7742.496737712059, 7747.147813740484, 7749.535027898316, 7750.457467977244, 7656.3109320161175, 7676.961615191888, 7691.192424795372, 7705.653245603933, 7708.768736937873, 7587.772366829004, 7614.733484328077, 7617.847643047108, 7625.199354067799, 7632.656530456087, 35209.64790352115, 35433.46849797255, 35445.29109417823, 35476.11309717252, 35541.28917421663, 3419.64093770153, 3458.429359155736, 3458.602260739997, 3460.8961731148106, 3475.548178736748, 29923.25191870199, 29968.0, 29974.100776976724, 29981.850090749624, 30026.399472010657, 5131.230315452674, 5134.306868572732, 5177.948220517822, 5223.503767142115, 5229.08491014071, 183943.0, 185350.0, 185541.0, 185656.0, 185991.0, 45999.31001034987, 46039.76980115078, 46109.538904611196, 46195.76902115468, 46216.0, 47565.859536320946, 47610.285845712344, 47643.0, 47646.52353476421, 47659.285110723366, 14140.646483837987, 14353.354099065567, 14369.497067602584, 14373.712525749426, 14381.352839122264, 10204.642837500653, 10338.328008679411, 10399.168066554657, 11524.250923689933, 11749.0, 13520.729585408237, 13526.188428694113, 13527.86472135266, 13561.660958476117, 13684.589462316146, 5562.471565201299, 5624.85937851557, 5644.3508996465935, 5664.320281566238, 5679.062954612526, 989.1394486796534, 1005.8692369992001, 1012.1245122968579, 1016.9237307201842, 1035.6478797208986, 22592.435189120402, 27394.178174654793, 28199.57700634492, 28657.140285791458, 28790.280242993675, 1076.4886678827495, 1079.1366906474946, 1089.5696200001114, 1090.4765712458066, 1091.879893211746, 12242.306692527629, 12462.43919023646, 12466.688332791571, 12524.24854508714, 12593.87406125927, 225892.0, 226698.0, 227499.0, 227989.0, 228111.0, 48399.75800120977, 48491.51508484941, 48648.75675621599, 48923.266151007054, 49028.26457603139, 1401.9369128389044, 1418.6311558994735, 1433.7490939085826, 1434.175349174229, 1438.503716217903, 22449.43876403103, 22679.546409072056, 22716.545669086194, 22793.0, 23054.884725576263, 35537.28925421425, 35839.0, 35865.924022279374, 35874.641253588175, 35905.64094359023, 184965.0, 185809.0, 186396.0, 186406.0, 186733.0, 32062.83968580142, 32395.51406728854, 33350.33299334048, 35034.64965350314, 35316.47025294571, 43291.134177315646, 43302.0, 43566.56433435625, 43820.0, 44016.77991610021, 32484.83757581197, 32605.18487037843, 32628.0, 32687.0, 33221.50167747534, 30780.692193078678, 30864.911773213804, 31301.68698313079, 31692.683073168973, 32294.83852580722, 35363.46954795629, 35528.822355888056, 35731.285374292886, 35929.48493605423, 36038.27923441569, 31019.534706979866, 31050.378992419573, 31119.533207002365, 31363.37273254568, 31491.527627085154, 22882.65676014828, 23554.411139721644, 23648.290551283488, 24041.398965026016, 24212.0], "value": 16715.84987174239, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [543.3262754184811, 552.7236381809096, 557.8131326005788, 559.4685049203258, 573.7131434282859, 21040.158393664253, 21541.46146346342, 21543.461413464673, 21619.0, 21758.12967481299, 24308.878455607697, 24706.87646561769, 24833.75166248337, 24839.37901552462, 24862.378440538996, 6513.250976137746, 6683.598984060947, 6684.699188536503, 6705.832354191136, 6706.530542861978, 6458.192725909262, 6639.601623902581, 7116.715331386738, 7120.28797120289, 7120.608366539838, 7135.3934915532245, 7141.178764442104, 7143.821404464878, 7156.821079473003, 7173.784786456415, 7126.358627723504, 7132.215456299803, 7133.8216544586285, 7136.500444968854, 7142.7857164285415, 6981.82545436363, 7008.789736307868, 7017.82455438613, 7020.438364930793, 7025.789226323167, 24322.756772432225, 24435.26694199177, 24517.0, 26830.195094147206, 30596.69403305983, 2004.2083377065978, 2064.143380497092, 2065.473304307407, 2066.039291729338, 2067.0284966065924, 20189.697154542693, 20821.063052162688, 20948.895255523625, 21034.368968930958, 21093.367198984055, 2530.177692250026, 2541.885615147323, 2614.790816734657, 2618.2930608735724, 2625.12058460416, 133302.0, 136525.0, 137007.999999999, 137137.999999999, 137221.3138934299, 19311.613767724568, 20147.597048058957, 21434.78565214359, 21635.67546486804, 21793.67309490359, 41709.1658166835, 41797.79101104475, 41838.58161418408, 41859.372109418386, 41994.58005419968, 10718.41048742311, 10878.129749620011, 10917.56329746817, 10929.453527323602, 11118.277311974696, 7923.801904952422, 8878.689245876363, 8903.777405564913, 8965.731028069169, 9034.367594268339, 9324.487153206503, 10904.236703430684, 11155.386453745123, 11332.376719280519, 11388.216948881489, 3592.0192878856933, 3683.6738774041323, 3978.5822488638278, 4109.445224894601, 4158.272302347077, 913.7578541686458, 930.7719608695913, 939.4081728510994, 971.9271054670928, 983.090641156927, 19052.237910483425, 19164.904175479034, 19505.90247048784, 19675.11461984214, 22574.774252257597, 1006.7231511334442, 1024.288119756762, 1029.2949329709172, 1040.9375437473775, 1048.208602505119, 10787.244892857425, 10821.322694982291, 11096.0, 11266.774664506827, 12682.746345073047, 483453.0, 493253.0, 501308.0, 517073.0, 518787.0, 110724.0, 110811.0, 111096.0, 113916.43041784904, 114196.0, 1190.3869507203797, 1199.772043311768, 1228.3060071059822, 1228.7173949991372, 1231.5566396097397, 21163.576728465036, 21247.681284780432, 21254.46863828417, 21306.467338316044, 21324.360269191948, 56483.0, 58532.12201816891, 59114.40885591089, 59613.0, 59616.70191649014, 69771.30228697648, 72156.0, 72504.0, 73749.26250737424, 73987.26012739804, 14234.288285585886, 14359.353829077507, 14385.064970776866, 14451.349689263798, 14473.0, 22116.33650990473, 22317.330480085624, 22418.66372004454, 22476.66285005759, 22529.549409012056, 17607.735883961494, 17709.468715938543, 17825.821741782416, 17992.640147196722, 18036.36872709449, 17060.146992650567, 17095.316187352368, 17173.141342933053, 17434.128293585523, 17445.82554174493, 18632.81367186365, 19114.0, 19162.329318473785, 19208.0, 19586.804131959067, 14211.644708881948, 14244.0, 14386.136831790127, 14497.492587759378, 14502.492412765503, 18386.448406547825, 18415.53961150982, 18468.0, 18532.166052527937, 18549.35077272343], "value": 12644.086813335665, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1259.2318685601783, 1290.2516540406561, 1304.9347532623367, 1320.8348956380453, 1325.3307079924643, 14246.857531424695, 14636.268186590682, 14867.479638212664, 14886.404543818262, 14893.25533723315, 14986.475473358434, 15183.468578599752, 15218.771718424205, 15248.542543723679, 15267.77098343523, 2690.4350086481863, 2735.86320683966, 2737.055715778063, 2737.9178624641295, 2738.5070687276284, 2564.7050589182204, 2622.514834755567, 2659.0294542491947, 2664.2806442260576, 2664.440467501827, 2657.5349313870092, 2669.3326668332916, 2680.142354446578, 2697.4470233602165, 2698.9325266868386, 2632.8946842126293, 2633.7366263373606, 2649.774769144625, 2653.62849201111, 2659.3750468639855, 2601.9349516262055, 2622.396848724785, 2641.366072142672, 2642.960355594668, 2643.458091091341, 14109.082909610843, 14446.061006034679, 14448.638784030483, 14461.927690361586, 14473.565793026226, 7426.331630153258, 7651.0, 7669.348105411047, 7674.386049116058, 7676.846463070708, 5167.48325167484, 5255.86860328491, 5318.680879147263, 5408.756605952742, 5413.783448662049, 1837.0906401331426, 1872.953176170593, 1888.282452667991, 1908.7709474862966, 1939.7575303087115, 24906.37734056645, 25151.0, 25316.0, 25319.240422787345, 25324.746752532606, 6315.115883776275, 6432.13166222554, 6499.5775274607, 6575.309592492815, 6602.405783479414, 16430.83569164317, 16440.095794731405, 16440.424585139463, 16453.83546164547, 16463.094529800976, 7321.194668586451, 7533.434992375484, 7557.735479258199, 7586.355159811367, 7597.202293759186, 5634.971825140848, 5809.593328466966, 6057.091436284592, 6081.087836824423, 6996.405305549085, 5062.620303477254, 5175.0, 5251.842444726664, 5274.604404669665, 5275.868103297448, 1385.9168449893039, 1401.8457969623332, 1414.2645824171373, 1423.0678905317034, 1424.0530047518491, 828.958552072394, 845.1970627903517, 849.5964416901923, 855.1918437076903, 871.9956400218085, 7066.646667666699, 7181.856362872818, 7190.892136617955, 7200.675969581382, 7251.274872512707, 678.7692184657142, 696.1472196559174, 919.1911118215927, 963.3016063354153, 968.9612415503301, 4604.953950460519, 4856.0, 4859.271109333557, 5010.473900240495, 5114.87212819675, 20463.590728185354, 20937.79062209389, 21233.575328493345, 21399.89300053521, 21491.78508214929, 20810.89594552048, 21048.684269735968, 21097.89451052735, 21183.470413239484, 21248.25631102904, 10542.26204165701, 11182.552697891993, 11191.72020699489, 11400.258983166068, 11415.714607134889, 8397.874031889649, 8573.871391929251, 8660.610272537624, 8813.074627164055, 8864.246539044254, 18039.508756196912, 18083.0, 18229.45311640653, 18317.267309307477, 18513.90743046276, 18473.0, 18641.44075677732, 18801.247950081844, 18836.905815470836, 19065.332713354965, 11761.588344407905, 12059.0, 12064.155509114278, 12175.573854915036, 12209.267443953391, 13407.32963351809, 13462.124961877447, 13625.114367566077, 13758.0, 13764.105333153313, 10673.146148308271, 10702.357858528514, 10723.088537474401, 10729.892701073202, 10916.508757106109, 10470.790584188122, 11018.889811102106, 11036.779264414827, 11179.66461006171, 11283.097352211968, 9551.474668892999, 9552.522373881417, 9571.808563828545, 9670.226381889574, 9698.85451718239, 6924.723011079702, 6996.720131194696, 7083.185433675193, 7143.999840022505, 7190.137183538009, 7094.751683691046, 7112.253213412517, 7139.892901606584, 7174.174969878531, 7238.855222895408], "value": 6445.8132747327545, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.55007378454906, 497.06995543169967, 514.0900605927507, 525.3905469655201, 527.3618921105463, 6368.52236082294, 6413.8717225655455, 6422.935770642298, 6436.292007879129, 6503.252126005499, 6096.817095487131, 6208.192934918454, 6279.215098112736, 6305.2433707955, 6308.9684551577175, 5077.41609714882, 5085.771140298677, 5091.541761241488, 5104.974475127619, 5113.718745468997, 5054.797808087672, 5060.2409638554145, 5085.898282034357, 5111.974440127813, 5121.641485096045, 4844.15227335215, 4968.950310496885, 5038.773255203525, 5047.141985862394, 5067.822626208083, 5049.141645920203, 5066.0, 5066.569341605968, 5088.821891233789, 5091.490850914916, 4479.39528163697, 4487.708298960557, 4548.8862778430475, 4552.726836389827, 4557.817687292504, 6316.210473690789, 6323.367663233686, 6351.555391122623, 6355.364463553654, 6360.55476116672, 5793.333766616851, 5794.82615521535, 5823.767049318022, 5830.3586605473365, 5833.300003999505, 6628.138342015555, 6641.634710090943, 6751.459883209332, 6778.864422711518, 6804.795856124275, 4857.73282469464, 4867.196912509432, 4897.436794768611, 4898.191798353268, 4923.7784299706245, 7077.221505634376, 7110.57336559808, 7157.57054576727, 7182.174049984266, 7195.136583609948, 5104.897902041938, 5111.46329635383, 5122.948770512322, 5127.128388174019, 5151.922721159186, 5123.38707259293, 5136.640435169502, 5151.175811870083, 5187.221916712523, 5207.869803254949, 5850.151727999416, 5854.502367298742, 5857.970710146507, 5868.4424979626865, 5885.293764748258, 6785.796426107225, 6810.182778066664, 6821.795346139525, 6863.416609588141, 6882.174139103339, 1669.991650041742, 1707.9829201708073, 1716.2191203002603, 1757.261949981011, 1763.7618921445442, 1212.1817773003188, 1235.3329202230875, 1247.6444213399138, 1253.8119282107757, 1257.9747505782757, 5810.80405776821, 5820.970895145497, 5874.823755287348, 5892.0, 5897.203877476486, 6785.830354241183, 6823.38589526945, 6854.840725171555, 6865.9313406866295, 6895.000674706418, 5820.0, 5945.910811337833, 5977.701114944235, 5998.760049598054, 6010.338862725097, 3457.75795694299, 3635.636436356343, 3645.8177091145867, 3654.8538058477893, 3690.5940346561856, 6638.634875081821, 6643.548822289078, 6659.500537459709, 6661.566998145105, 6672.366125218096, 7025.437964962893, 7105.538140020882, 7114.92885071153, 7123.572585644878, 7146.3568278855155, 6703.497237707289, 6738.225104113089, 6772.679327531084, 6787.423069039187, 6793.150856142982, 6796.150481189851, 6816.48876334267, 6969.965150174216, 6991.860162796817, 6999.195092564217, 6732.360425759545, 6824.454043676593, 6826.180858297036, 6851.828704282233, 6853.348931851466, 6528.967355163194, 6556.967340124576, 6677.732890684319, 6702.564333318319, 6712.39588437043, 5020.748962551931, 5029.321041659476, 5045.571126454146, 5080.7205603692155, 5092.159793634009, 6638.668066596554, 6667.8666426670225, 6713.5089753328975, 6754.677006715608, 6838.589684618939, 6464.935350646433, 6552.410283074546, 6623.105880706235, 6651.567648102858, 6683.498737594797, 6647.7008534617025, 6665.166854143232, 6697.296783837627, 6715.93284067153, 6739.809561892503, 6384.808455746335, 6385.4355682857995, 6517.0, 6539.149910511794, 6562.0, 6451.70967306463, 6470.547061705635, 6567.540272180901, 6610.206775187008, 6668.399844014062, 6432.774852880127, 6432.903506447501, 6467.159269294962, 6489.3510648934725, 6574.425574425574], "value": 5151.723994622634, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [778.4823092643392, 803.8697451752856, 818.0714888601435, 846.3356265331715, 889.4752096263207, 17626.0, 18133.818661813395, 18160.81839181608, 18165.455036348896, 21389.67915481269, 20536.0, 21201.0, 21205.257815976445, 21211.787882121174, 21269.0, 894.7315805258413, 908.4322298563397, 912.1972664055622, 913.6071489259616, 920.8158368326328, 880.8458519759049, 955.4267439536292, 956.0726095687207, 956.9330146889721, 960.1310813713566, 1011.5650270383769, 1015.6039144733522, 1015.6089905386406, 1015.9746006349864, 1016.0246163682837, 996.6362277768635, 996.6661168508558, 996.7209181429175, 997.286939838012, 1000.0999100809257, 739.8002539314355, 740.9629518524106, 744.426791370647, 744.4974642116549, 744.5718711740717, 18715.344962926232, 19139.0, 19225.13486893093, 19327.323543675902, 20060.49848753778, 20727.481812954647, 20801.0, 21119.5776084479, 21212.363629091153, 21214.25750098739, 16918.561922236626, 17407.30370785167, 17520.21159047846, 17522.47432577012, 17656.558586035324, 3736.532933383327, 3750.2499500099816, 3771.585125636178, 3772.4152756322815, 3772.9245415091546, 127243.0, 127601.0, 131935.34032329873, 134332.32833835768, 134914.3254283732, 15599.376024958874, 15658.373665053497, 15833.287502062434, 16045.598860028593, 16219.675606488041, 28449.85775071153, 28593.0, 28717.712822871505, 28831.56752648712, 29327.120186394015, 7411.962940185264, 7815.218478152253, 8470.364722645827, 8528.360372972053, 8559.74320770378, 4234.428352172417, 4289.721168124124, 4294.441722576107, 4303.4190384298345, 4315.503717072514, 5003.0, 5134.691918484903, 5140.280360749498, 5164.096283150434, 5209.01028804526, 971.5627967414691, 983.8032393521324, 993.2798720927415, 995.5121990224883, 996.4220751963844, 633.5438484291395, 678.2437582096026, 689.334791925784, 698.4168219536743, 699.6117154978933, 11085.778284434266, 11577.536898524133, 11694.415279236004, 11712.297262164297, 11820.822687659693, 637.4326849104208, 655.4297760948006, 666.2305037681485, 687.71116131225, 698.9685464154126, 5442.673439593637, 5592.5805564582815, 5729.369769325371, 5785.88428231433, 5821.4979817610665, 105677.47161264298, 107508.46245768722, 112277.0, 112379.0, 114336.0, 96369.0, 98710.5064474673, 99529.0, 100516.49741751244, 103239.48380258051, 88562.55718721494, 90603.54698226598, 91053.54473227724, 91799.5410022959, 93588.53205733928, 10266.538005789607, 10362.119219866094, 10376.792464150825, 10634.946825265824, 12934.159279646792, 40823.38764918469, 42142.36786448144, 42282.36576451417, 42535.361969569865, 42798.57201427946, 67785.66107169433, 68849.0, 70487.0, 71862.28137718556, 72192.63903680448, 17576.56058598545, 17858.46424607264, 18201.81798182001, 18529.166187521863, 18617.813821861608, 17952.461426157235, 18047.0, 18267.725984109984, 19103.14035868417, 19245.230190792772, 17764.644707106043, 18177.09114544294, 18180.818191818442, 18616.0, 18842.623147537248, 18120.456386308433, 18527.81472185315, 18707.625847483247, 18981.715274271173, 19593.0, 16984.235709393353, 17016.149192540077, 17130.571735706708, 17328.13359332003, 17763.555911102325, 13324.200547967153, 13401.463941442233, 13518.459261629425, 13678.0, 13921.025528212927, 14070.155790652594, 14367.640808979859, 14526.636834079232, 14713.852861471249, 15066.472673456376], "value": 9002.991646467366, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1516.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 108.0, 99.0, 107.0, 112.0, 108.0, 114.0, 97.0, 108.0, 107.0], "value": 105.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 119.0, 150.0, 177.0, 118.0, 166.0, 145.0, 122.0, 175.0, 117.0], "value": 143.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 161.0, 163.0, 161.0, 162.0, 164.0, 181.0, 173.0, 163.0, 162.0], "value": 166.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 73.0, 175.0, 85.0, 82.0, 73.0, 82.0, 163.0, 82.0], "value": 96.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 129.0, 79.0, 78.0, 83.0, 128.0, 80.0, 78.0, 125.0], "value": 94.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 114.0, 114.0, 113.0, 114.0, 118.0, 113.0, 114.0, 118.0, 117.0], "value": 115.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 150.0, 148.0, 149.0, 147.0, 147.0, 150.0, 147.0, 148.0, 149.0], "value": 148.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 94.0, 94.0, 93.0, 94.0, 96.0, 94.0, 94.0, 101.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 52.0, 51.0, 52.0, 55.0, 51.0, 52.0, 52.0, 52.0, 52.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 55.0, 55.0, 55.0, 57.0, 56.0, 55.0, 56.0], "value": 55.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 82.0, 75.0, 75.0, 76.0, 74.0, 75.0, 78.0, 74.0, 75.0], "value": 76.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 126.0, 117.0, 116.0, 128.0, 120.0, 118.0, 125.0, 120.0, 152.0], "value": 125.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 179.0, 177.0, 169.0, 172.0, 175.0, 170.0, 176.0, 170.0, 171.0], "value": 174.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 74.0, 66.0, 67.0, 64.0, 62.0, 69.0, 65.0, 63.0], "value": 67.0, "unit": "score"}]}]} 05:12:37 INFO - Return code: 0 05:12:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:12:37 INFO - # TBPL SUCCESS # 05:12:37 INFO - Running post-action listener: _package_coverage_data 05:12:37 INFO - Running post-action listener: _resource_record_post_action 05:12:37 INFO - [mozharness: 2017-08-25 12:12:37.460304Z] Finished run-tests step (success) 05:12:37 INFO - Running post-run listener: _resource_record_post_run 05:12:37 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 16384; Write bytes: 428281856; Read time: 240; Write time: 232016 05:12:37 INFO - TinderboxPrint: CPU usage
13.1% 05:12:37 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 240 05:12:37 INFO - TinderboxPrint: I/O write bytes / time
428,281,856 / 232,016 05:12:37 INFO - TinderboxPrint: CPU idle
8,267.8 (86.9%) 05:12:37 INFO - TinderboxPrint: CPU user
1,200.0 (12.6%) 05:12:37 INFO - TinderboxPrint: Swap in / out
0 / 0 05:12:37 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 105701376; Read time: 60; Write time: 108800 05:12:37 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:12:37 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 8192; Write bytes: 322580480; Read time: 168; Write time: 123216 05:12:38 INFO - Running post-run listener: _upload_blobber_files 05:12:38 INFO - Blob upload gear active. 05:12:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:12:38 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 05:12:38 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'] 05:12:38 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 05:12:38 INFO - (blobuploader) - INFO - Open directory for files ... 05:12:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:12:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:12:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:12:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:12:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:12:39 INFO - (blobuploader) - INFO - Done attempting. 05:12:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:12:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:12:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:12:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:12:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:12:40 INFO - (blobuploader) - INFO - Done attempting. 05:12:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:12:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:12:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:12:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:12:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:12:43 INFO - (blobuploader) - INFO - Done attempting. 05:12:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:12:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:12:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:12:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:12:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:12:44 INFO - (blobuploader) - INFO - Done attempting. 05:12:44 INFO - (blobuploader) - INFO - Iteration through files over. 05:12:44 INFO - Return code: 0 05:12:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:12:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:12:44 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34ddf2adff4bcdf2370e03020113b5ad35b1c62d10d71ba5b6b41bc34e5aa87744e2443303f03e0eeccc7d78157639c2d8cc248811055b66590598ffef6b7a43", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72df89a379b775e7a9928bea92c564d069117533d1c16a076fb69999058bd07c4f7e91c26d9ecb11259229ad1ea74e1e81b72a8a5e511746642b1c76727b35ac", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/372586c20aa460bce020d4711f8cc810e951d924bcb68be1025a2df06d4065dea39c9f852654721830d4c48eda3732507f5e24d5df662028926c779c31ce90ed", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d78358866402630d5cc48d4d88a86311b6ddfacec31dc476512b5f0fad37618d80e34d21dc5b9f2b157ea97ec036461695bd83ff3b9b01aee423ca40de5faeeb"} 05:12:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:12:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:12:44 INFO - Contents: 05:12:44 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34ddf2adff4bcdf2370e03020113b5ad35b1c62d10d71ba5b6b41bc34e5aa87744e2443303f03e0eeccc7d78157639c2d8cc248811055b66590598ffef6b7a43", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72df89a379b775e7a9928bea92c564d069117533d1c16a076fb69999058bd07c4f7e91c26d9ecb11259229ad1ea74e1e81b72a8a5e511746642b1c76727b35ac", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/372586c20aa460bce020d4711f8cc810e951d924bcb68be1025a2df06d4065dea39c9f852654721830d4c48eda3732507f5e24d5df662028926c779c31ce90ed", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d78358866402630d5cc48d4d88a86311b6ddfacec31dc476512b5f0fad37618d80e34d21dc5b9f2b157ea97ec036461695bd83ff3b9b01aee423ca40de5faeeb"} 05:12:44 INFO - Running post-run listener: copy_logs_to_upload_dir 05:12:44 INFO - Copying logs to upload dir... 05:12:44 INFO - mkdir: /builds/slave/test/build/upload/logs 05:12:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.349311 ========= master_lag: 0.31 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-08-25 05:12:44.625965) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-25 05:12:44.633397) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392 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/34ddf2adff4bcdf2370e03020113b5ad35b1c62d10d71ba5b6b41bc34e5aa87744e2443303f03e0eeccc7d78157639c2d8cc248811055b66590598ffef6b7a43", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72df89a379b775e7a9928bea92c564d069117533d1c16a076fb69999058bd07c4f7e91c26d9ecb11259229ad1ea74e1e81b72a8a5e511746642b1c76727b35ac", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/372586c20aa460bce020d4711f8cc810e951d924bcb68be1025a2df06d4065dea39c9f852654721830d4c48eda3732507f5e24d5df662028926c779c31ce90ed", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d78358866402630d5cc48d4d88a86311b6ddfacec31dc476512b5f0fad37618d80e34d21dc5b9f2b157ea97ec036461695bd83ff3b9b01aee423ca40de5faeeb"} build_url:https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011348 build_url: 'https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34ddf2adff4bcdf2370e03020113b5ad35b1c62d10d71ba5b6b41bc34e5aa87744e2443303f03e0eeccc7d78157639c2d8cc248811055b66590598ffef6b7a43", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72df89a379b775e7a9928bea92c564d069117533d1c16a076fb69999058bd07c4f7e91c26d9ecb11259229ad1ea74e1e81b72a8a5e511746642b1c76727b35ac", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/372586c20aa460bce020d4711f8cc810e951d924bcb68be1025a2df06d4065dea39c9f852654721830d4c48eda3732507f5e24d5df662028926c779c31ce90ed", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d78358866402630d5cc48d4d88a86311b6ddfacec31dc476512b5f0fad37618d80e34d21dc5b9f2b157ea97ec036461695bd83ff3b9b01aee423ca40de5faeeb"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-25 05:12:44.659248) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 05:12:44.659537) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1503661552.549701-805353392 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004439 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 05:12:44.743793) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-25 05:12:44.744940) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-25 05:12:44.745234) ========= ========= Total master_lag: 0.46 =========