builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-087 starttime: 1505126810.41 results: success (0) revision: f9a5e9ed62103c84e4cde915f4d08f1ce71be83e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-11 03:46:50.408386) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-11 03:46:50.408858) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-11 03:46:50.409132) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-11 03:46:50.426743) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 03:46:50.427226) ========= 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=ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-11 03:46:50-- 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% 52.7M=0s 2017-09-11 03:46:50 (52.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.134769 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 03:46:50.579773) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 03:46:50.580083) ========= 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=ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014981 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 03:46:50.630369) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 03:46:50.630702) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f9a5e9ed62103c84e4cde915f4d08f1ce71be83e --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 f9a5e9ed62103c84e4cde915f4d08f1ce71be83e --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=ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-11 03:46:50,682 truncating revision to first 12 chars 2017-09-11 03:46:50,682 Setting DEBUG logging. 2017-09-11 03:46:50,682 attempt 1/10 2017-09-11 03:46:50,682 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f9a5e9ed6210?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-11 03:46:51,152 unpacking tar archive at: mozilla-central-f9a5e9ed6210/testing/mozharness/ program finished with exit code 0 elapsedTime=0.774414 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 03:46:51.421931) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-11 03:46:51.422296) ========= script_repo_revision: f9a5e9ed62103c84e4cde915f4d08f1ce71be83e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-11 03:46:51.422693) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-11 03:46:51.422991) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-11 03:46:51.436130) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-09-11 03:46:51.436478) ========= /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=ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:46:51 INFO - MultiFileLogger online at 20170911 03:46:51 in /builds/slave/test 03:46:51 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 03:46:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:46:51 INFO - {'append_to_log': False, 03:46:51 INFO - 'base_work_dir': '/builds/slave/test', 03:46:51 INFO - 'blob_upload_branch': 'Firefox', 03:46:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:46:51 INFO - 'branch': 'Firefox', 03:46:51 INFO - 'buildbot_json_path': 'buildprops.json', 03:46:51 INFO - 'code_coverage': False, 03:46:51 INFO - 'config_files': ('talos/linux_config.py',), 03:46:51 INFO - 'default_actions': ('clobber', 03:46:51 INFO - 'read-buildbot-config', 03:46:51 INFO - 'download-and-extract', 03:46:51 INFO - 'populate-webroot', 03:46:51 INFO - 'create-virtualenv', 03:46:51 INFO - 'install', 03:46:51 INFO - 'setup-mitmproxy', 03:46:51 INFO - 'run-tests'), 03:46:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:46:51 INFO - 'disable_ccov_upload': False, 03:46:51 INFO - 'disable_stylo': False, 03:46:51 INFO - 'download_minidump_stackwalk': True, 03:46:51 INFO - 'download_symbols': 'ondemand', 03:46:51 INFO - 'enable_stylo': False, 03:46:51 INFO - 'enable_webrender': False, 03:46:51 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:46:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:46:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:46:51 INFO - 'gecko_profile': False, 03:46:51 INFO - 'gecko_profile_interval': 0, 03:46:51 INFO - 'installer_path': 'installer.exe', 03:46:51 INFO - 'log_level': 'info', 03:46:51 INFO - 'log_name': 'talos', 03:46:51 INFO - 'log_to_console': True, 03:46:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:46:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:46:51 INFO - 'opt_config_files': (), 03:46:51 INFO - 'pip_index': False, 03:46:51 INFO - 'suite': 'dromaeojs-e10s', 03:46:51 INFO - 'system_bits': '32', 03:46:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:46:51 INFO - 'title': 'talos-linux64-ix-087', 03:46:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:46:51 INFO - 'use_talos_json': True, 03:46:51 INFO - 'verify': 'False', 03:46:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:46:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:46:51 INFO - 'work_dir': 'build'} 03:46:51 INFO - [mozharness: 2017-09-11 10:46:51.636427Z] Running clobber step. 03:46:51 INFO - Running pre-action listener: _resource_record_pre_action 03:46:51 INFO - Running main action method: clobber 03:46:51 INFO - rmtree: /builds/slave/test/build 03:46:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:46:53 INFO - Running post-action listener: _resource_record_post_action 03:46:53 INFO - [mozharness: 2017-09-11 10:46:53.171177Z] Finished clobber step (success) 03:46:53 INFO - [mozharness: 2017-09-11 10:46:53.171275Z] Running read-buildbot-config step. 03:46:53 INFO - Running pre-action listener: _resource_record_pre_action 03:46:53 INFO - Running main action method: read_buildbot_config 03:46:53 INFO - Using buildbot properties: 03:46:53 INFO - { 03:46:53 INFO - "project": "", 03:46:53 INFO - "product": "firefox", 03:46:53 INFO - "who": "archaeopteryx@coole-files.de", 03:46:53 INFO - "installer_path": "public/build/target.tar.bz2", 03:46:53 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:46:53 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:46:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 03:46:53 INFO - "stage_platform": "linux64-pgo", 03:46:53 INFO - "basedir": "/builds/slave/test", 03:46:53 INFO - "buildnumber": 176, 03:46:53 INFO - "platform": "ubuntu64_hw", 03:46:53 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:46:53 INFO - "slavebuilddir": "test", 03:46:53 INFO - "taskId": "cMYixBCVR2S6Fr9RgGz6-w", 03:46:53 INFO - "branch": "mozilla-central", 03:46:53 INFO - "script_repo_revision": "production", 03:46:53 INFO - "revision": "f9a5e9ed62103c84e4cde915f4d08f1ce71be83e", 03:46:53 INFO - "slavename": "talos-linux64-ix-087", 03:46:53 INFO - "repo_path": "mozilla-central" 03:46:53 INFO - } 03:46:53 INFO - Finding installer, test and symbols from parent task. 03:46:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cMYixBCVR2S6Fr9RgGz6-w'}, attempt #1 03:46:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cMYixBCVR2S6Fr9RgGz6-w'}, attempt #1 03:46:54 INFO - Task dependencies: XZ_hBdiHR7SWWTd1kz6MXA 03:46:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XZ_hBdiHR7SWWTd1kz6MXA'}, attempt #1 03:46:54 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/XZ_hBdiHR7SWWTd1kz6MXA/artifacts/public/build/target.tar.bz2 03:46:54 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/XZ_hBdiHR7SWWTd1kz6MXA/artifacts/public/build/target.test_packages.json 03:46:54 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/XZ_hBdiHR7SWWTd1kz6MXA/artifacts/public/build/target.crashreporter-symbols.zip 03:46:54 INFO - Running post-action listener: _resource_record_post_action 03:46:54 INFO - [mozharness: 2017-09-11 10:46:54.510433Z] Finished read-buildbot-config step (success) 03:46:54 INFO - [mozharness: 2017-09-11 10:46:54.510651Z] Running download-and-extract step. 03:46:54 INFO - Running pre-action listener: _resource_record_pre_action 03:46:54 INFO - Running main action method: download_and_extract 03:46:54 INFO - mkdir: /builds/slave/test/build/tests 03:46:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:54 INFO - trying https://queue.taskcluster.net/v1/task/XZ_hBdiHR7SWWTd1kz6MXA/artifacts/public/build/target.test_packages.json 03:46:54 INFO - Downloading https://queue.taskcluster.net/v1/task/XZ_hBdiHR7SWWTd1kz6MXA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:46:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XZ_hBdiHR7SWWTd1kz6MXA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:46:55 INFO - Downloaded 989 bytes. 03:46:55 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:46:55 INFO - Using the following test package requirements: 03:46:55 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:46:55 INFO - u'common': [u'target.common.tests.zip'], 03:46:55 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:46:55 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:46:55 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:46:55 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:46:55 INFO - u'mozbase': [u'target.common.tests.zip'], 03:46:55 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:46:55 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:46:55 INFO - u'web-platform': [u'target.common.tests.zip', 03:46:55 INFO - u'target.web-platform.tests.tar.gz'], 03:46:55 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:46:55 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:46:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XZ_hBdiHR7SWWTd1kz6MXA/artifacts/public/build/target.common.tests.zip 03:46:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XZ_hBdiHR7SWWTd1kz6MXA/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:46:55 INFO - Fetch https://queue.taskcluster.net/v1/task/XZ_hBdiHR7SWWTd1kz6MXA/artifacts/public/build/target.common.tests.zip into memory 03:46:56 INFO - Content-Length response header: 55692143 03:46:56 INFO - Bytes received: 55692143 03:47:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XZ_hBdiHR7SWWTd1kz6MXA/artifacts/public/build/target.talos.tests.zip 03:47:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XZ_hBdiHR7SWWTd1kz6MXA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:47:07 INFO - Fetch https://queue.taskcluster.net/v1/task/XZ_hBdiHR7SWWTd1kz6MXA/artifacts/public/build/target.talos.tests.zip into memory 03:47:07 INFO - Content-Length response header: 14497963 03:47:07 INFO - Bytes received: 14497963 03:47:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:08 INFO - trying https://queue.taskcluster.net/v1/task/XZ_hBdiHR7SWWTd1kz6MXA/artifacts/public/build/target.tar.bz2 03:47:08 INFO - Downloading https://queue.taskcluster.net/v1/task/XZ_hBdiHR7SWWTd1kz6MXA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:47:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XZ_hBdiHR7SWWTd1kz6MXA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:47:09 INFO - Downloaded 63658640 bytes. 03:47:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XZ_hBdiHR7SWWTd1kz6MXA/artifacts/public/build/target.tar.bz2 03:47:09 INFO - mkdir: /builds/slave/test/properties 03:47:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:47:09 INFO - Writing to file /builds/slave/test/properties/build_url 03:47:09 INFO - Contents: 03:47:09 INFO - build_url:https://queue.taskcluster.net/v1/task/XZ_hBdiHR7SWWTd1kz6MXA/artifacts/public/build/target.tar.bz2 03:47:09 INFO - Running post-action listener: _resource_record_post_action 03:47:09 INFO - Running post-action listener: find_tests_for_verification 03:47:09 INFO - Running post-action listener: set_extra_try_arguments 03:47:09 INFO - [mozharness: 2017-09-11 10:47:09.170928Z] Finished download-and-extract step (success) 03:47:09 INFO - [mozharness: 2017-09-11 10:47:09.171025Z] Running populate-webroot step. 03:47:09 INFO - Running pre-action listener: _resource_record_pre_action 03:47:09 INFO - Running main action method: populate_webroot 03:47:09 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:47:09 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:47:09 INFO - 'tests': ['tresize', 03:47:09 INFO - 'tcanvasmark']}, 03:47:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:47:09 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:47:09 INFO - 'tests': ['dromaeo_css', 03:47:09 INFO - 'kraken']}, 03:47:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:47:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:47:09 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:47:09 INFO - 'talos_options': ['--disable-stylo'], 03:47:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:47:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:47:09 INFO - 'tests': ['damp', 'tps']}, 03:47:09 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:47:09 INFO - 'talos_options': ['--disable-stylo'], 03:47:09 INFO - 'tests': ['damp', 'tps']}, 03:47:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:47:09 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:47:09 INFO - 'tests': ['dromaeo_dom']}, 03:47:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:47:09 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:47:09 INFO - 'tests': ['basic_compositor_video', 03:47:09 INFO - 'glvideo']}, 03:47:09 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:47:09 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:47:09 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:47:09 INFO - 'talos_options': ['--disable-stylo'], 03:47:09 INFO - 'tests': ['ts_paint_webext', 03:47:09 INFO - 'tp5o_webext']}, 03:47:09 INFO - 'other-e10s': {'tests': ['a11yr', 03:47:09 INFO - 'ts_paint', 03:47:09 INFO - 'tpaint', 03:47:09 INFO - 'sessionrestore', 03:47:09 INFO - 'sessionrestore_many_windows', 03:47:09 INFO - 'sessionrestore_no_auto_restore', 03:47:09 INFO - 'tabpaint']}, 03:47:09 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:47:09 INFO - 'tests': ['a11yr', 03:47:09 INFO - 'ts_paint', 03:47:09 INFO - 'tpaint', 03:47:09 INFO - 'sessionrestore', 03:47:09 INFO - 'sessionrestore_many_windows', 03:47:09 INFO - 'sessionrestore_no_auto_restore', 03:47:09 INFO - 'tabpaint']}, 03:47:09 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:47:09 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:47:09 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:47:09 INFO - 'tests': ['bloom_basic_singleton']}, 03:47:09 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:47:09 INFO - 'tests': ['bloom_basic']}, 03:47:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:47:09 INFO - 'tsvgr_opacity', 03:47:09 INFO - 'tart', 03:47:09 INFO - 'tscrollx', 03:47:09 INFO - 'tsvg_static']}, 03:47:09 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:47:09 INFO - 'tests': ['tsvgx', 03:47:09 INFO - 'tsvgr_opacity', 03:47:09 INFO - 'tart', 03:47:09 INFO - 'tscrollx', 03:47:09 INFO - 'tsvg_static']}, 03:47:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:47:09 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:47:09 INFO - 'talos_options': ['--disable-stylo'], 03:47:09 INFO - 'tests': ['tp5o']}, 03:47:09 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:47:09 INFO - 'talos_options': ['--mitmproxy', 03:47:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:47:09 INFO - '--firstNonBlankPaint'], 03:47:09 INFO - 'tests': ['tp6_google', 03:47:09 INFO - 'tp6_youtube', 03:47:09 INFO - 'tp6_amazon', 03:47:09 INFO - 'tp6_facebook']}, 03:47:09 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:47:09 INFO - 'talos_options': ['--disable-stylo', 03:47:09 INFO - '--mitmproxy', 03:47:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:47:09 INFO - '--firstNonBlankPaint'], 03:47:09 INFO - 'tests': ['tp6_google', 03:47:09 INFO - 'tp6_youtube', 03:47:09 INFO - 'tp6_amazon', 03:47:09 INFO - 'tp6_facebook']}, 03:47:09 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:47:09 INFO - 'talos_options': ['--stylo-threads=1', 03:47:09 INFO - '--mitmproxy', 03:47:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:47:09 INFO - '--firstNonBlankPaint'], 03:47:09 INFO - 'tests': ['tp6_google', 03:47:09 INFO - 'tp6_youtube', 03:47:09 INFO - 'tp6_amazon', 03:47:09 INFO - 'tp6_facebook']}, 03:47:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:47:09 INFO - 'talos_options': ['--xperf_path', 03:47:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:47:09 INFO - 'tests': ['tp5n']}, 03:47:09 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:47:09 INFO - 'talos_options': ['--disable-stylo', 03:47:09 INFO - '--xperf_path', 03:47:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:47:09 INFO - 'tests': ['tp5n']}}} 03:47:09 INFO - Running post-action listener: _resource_record_post_action 03:47:09 INFO - [mozharness: 2017-09-11 10:47:09.185929Z] Finished populate-webroot step (success) 03:47:09 INFO - [mozharness: 2017-09-11 10:47:09.186018Z] Running create-virtualenv step. 03:47:09 INFO - Running pre-action listener: _resource_record_pre_action 03:47:09 INFO - Running main action method: create_virtualenv 03:47:09 INFO - Creating virtualenv /builds/slave/test/build/venv 03:47:09 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:47:09 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:47:09 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:47:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:47:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:47:09 INFO - Installing distribute.............................................................................................................................................................................................done. 03:47:10 INFO - Installing pip...............done. 03:47:10 INFO - Return code: 0 03:47:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:47:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:47:10 INFO - Reading from file tmpfile_stdout 03:47:10 INFO - Output received: 03:47:10 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:47:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:47:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:10 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 0x1bca1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d93a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f36a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a090>, '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 0x1f3ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f34f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', '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 03:47:10 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 03:47:10 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 03:47:10 INFO - Using env: {'DISPLAY': ':0', 03:47:10 INFO - 'HOME': '/home/cltbld', 03:47:10 INFO - 'LANG': 'en_US.UTF-8', 03:47:10 INFO - 'LANGUAGE': 'en_US:en', 03:47:10 INFO - 'LOGNAME': 'cltbld', 03:47:10 INFO - 'MAIL': '/var/mail/cltbld', 03:47:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:10 INFO - 'MOZ_NO_REMOTE': '1', 03:47:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:10 INFO - 'NO_EM_RESTART': '1', 03:47:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:10 INFO - 'PWD': '/builds/slave/test', 03:47:10 INFO - 'SHELL': '/bin/bash', 03:47:10 INFO - 'SHLVL': '1', 03:47:10 INFO - 'TERM': 'linux', 03:47:10 INFO - 'TMOUT': '86400', 03:47:10 INFO - 'USER': 'cltbld', 03:47:10 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', 03:47:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:10 INFO - '_': '/tools/buildbot/bin/python'} 03:47:11 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:47:11 INFO - Downloading/unpacking pip>=1.5 03:47:11 INFO - Running setup.py egg_info for package pip 03:47:11 INFO - warning: no files found matching 'pip/cacert.pem' 03:47:11 INFO - warning: no files found matching '*.html' under directory 'docs' 03:47:11 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:47:11 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:47:11 INFO - Installing collected packages: pip 03:47:11 INFO - Found existing installation: pip 1.1 03:47:11 INFO - Uninstalling pip: 03:47:11 INFO - Successfully uninstalled pip 03:47:11 INFO - Running setup.py install for pip 03:47:11 INFO - warning: no files found matching 'pip/cacert.pem' 03:47:11 INFO - warning: no files found matching '*.html' under directory 'docs' 03:47:11 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:47:11 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:47:11 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:47:11 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:47:11 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:47:11 INFO - Successfully installed pip 03:47:11 INFO - Cleaning up... 03:47:11 INFO - Return code: 0 03:47:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:47:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:11 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 0x1bca1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d93a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f36a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a090>, '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 0x1f3ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f34f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', '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 03:47:11 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 03:47:11 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 03:47:11 INFO - Using env: {'DISPLAY': ':0', 03:47:11 INFO - 'HOME': '/home/cltbld', 03:47:11 INFO - 'LANG': 'en_US.UTF-8', 03:47:11 INFO - 'LANGUAGE': 'en_US:en', 03:47:11 INFO - 'LOGNAME': 'cltbld', 03:47:11 INFO - 'MAIL': '/var/mail/cltbld', 03:47:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:11 INFO - 'MOZ_NO_REMOTE': '1', 03:47:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:11 INFO - 'NO_EM_RESTART': '1', 03:47:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:11 INFO - 'PWD': '/builds/slave/test', 03:47:11 INFO - 'SHELL': '/bin/bash', 03:47:11 INFO - 'SHLVL': '1', 03:47:11 INFO - 'TERM': 'linux', 03:47:11 INFO - 'TMOUT': '86400', 03:47:11 INFO - 'USER': 'cltbld', 03:47:11 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', 03:47:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:11 INFO - '_': '/tools/buildbot/bin/python'} 03:47:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:11 INFO - Downloading/unpacking psutil>=3.1.1 03:47:11 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 03:47:11 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 03:47:11 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 03:47:11 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 03:47:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:47:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:47:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:47:13 INFO - Installing collected packages: psutil 03:47:13 INFO - Running setup.py install for psutil 03:47:13 INFO - building 'psutil._psutil_linux' extension 03:47:13 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 03:47:13 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 03:47:13 INFO - building 'psutil._psutil_posix' extension 03:47:13 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 03:47:13 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 03:47:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:47:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:47:13 INFO - Successfully installed psutil 03:47:13 INFO - Cleaning up... 03:47:13 INFO - Return code: 0 03:47:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:47:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:13 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 0x1bca1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d93a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f36a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a090>, '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 0x1f3ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f34f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', '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 03:47:13 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 03:47:13 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 03:47:13 INFO - Using env: {'DISPLAY': ':0', 03:47:13 INFO - 'HOME': '/home/cltbld', 03:47:13 INFO - 'LANG': 'en_US.UTF-8', 03:47:13 INFO - 'LANGUAGE': 'en_US:en', 03:47:13 INFO - 'LOGNAME': 'cltbld', 03:47:13 INFO - 'MAIL': '/var/mail/cltbld', 03:47:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:13 INFO - 'MOZ_NO_REMOTE': '1', 03:47:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:13 INFO - 'NO_EM_RESTART': '1', 03:47:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:13 INFO - 'PWD': '/builds/slave/test', 03:47:13 INFO - 'SHELL': '/bin/bash', 03:47:13 INFO - 'SHLVL': '1', 03:47:13 INFO - 'TERM': 'linux', 03:47:13 INFO - 'TMOUT': '86400', 03:47:13 INFO - 'USER': 'cltbld', 03:47:13 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', 03:47:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:13 INFO - '_': '/tools/buildbot/bin/python'} 03:47:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:47:13 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 03:47:13 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 03:47:13 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 03:47:13 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 03:47:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:47:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:47:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:47:15 INFO - Installing collected packages: mozsystemmonitor 03:47:15 INFO - Running setup.py install for mozsystemmonitor 03:47:16 INFO - Successfully installed mozsystemmonitor 03:47:16 INFO - Cleaning up... 03:47:16 INFO - Return code: 0 03:47:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:47:16 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')]} 03:47:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:16 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')]} 03:47:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:16 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 0x1bca1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d93a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f36a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a090>, '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 0x1f3ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f34f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', '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 03:47:16 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 03:47:16 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 03:47:16 INFO - Using env: {'DISPLAY': ':0', 03:47:16 INFO - 'HOME': '/home/cltbld', 03:47:16 INFO - 'LANG': 'en_US.UTF-8', 03:47:16 INFO - 'LANGUAGE': 'en_US:en', 03:47:16 INFO - 'LOGNAME': 'cltbld', 03:47:16 INFO - 'MAIL': '/var/mail/cltbld', 03:47:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:16 INFO - 'MOZ_NO_REMOTE': '1', 03:47:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:16 INFO - 'NO_EM_RESTART': '1', 03:47:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:16 INFO - 'PWD': '/builds/slave/test', 03:47:16 INFO - 'SHELL': '/bin/bash', 03:47:16 INFO - 'SHLVL': '1', 03:47:16 INFO - 'TERM': 'linux', 03:47:16 INFO - 'TMOUT': '86400', 03:47:16 INFO - 'USER': 'cltbld', 03:47:16 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', 03:47:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:16 INFO - '_': '/tools/buildbot/bin/python'} 03:47:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:16 INFO - Downloading/unpacking jsonschema==2.5.1 03:47:16 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 03:47:16 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 03:47:16 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 03:47:16 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 03:47:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:47:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:47:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:17 INFO - Downloading functools32-3.2.3-2.tar.gz 03:47:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:47:17 INFO - warning: no files found matching '*.txt' 03:47:17 INFO - no previously-included directories found matching 'build' 03:47:17 INFO - no previously-included directories found matching 'dist' 03:47:17 INFO - no previously-included directories found matching '.git*' 03:47:17 INFO - Installing collected packages: jsonschema, functools32 03:47:18 INFO - Running setup.py install for functools32 03:47:18 INFO - warning: no files found matching '*.txt' 03:47:18 INFO - no previously-included directories found matching 'build' 03:47:18 INFO - no previously-included directories found matching 'dist' 03:47:18 INFO - no previously-included directories found matching '.git*' 03:47:18 INFO - Successfully installed jsonschema functools32 03:47:18 INFO - Cleaning up... 03:47:18 INFO - Return code: 0 03:47:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:47:18 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')]} 03:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:18 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')]} 03:47:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:18 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 0x1bca1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d93a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f36a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a090>, '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 0x1f3ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f34f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', '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 03:47:18 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 03:47:18 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 03:47:18 INFO - Using env: {'DISPLAY': ':0', 03:47:18 INFO - 'HOME': '/home/cltbld', 03:47:18 INFO - 'LANG': 'en_US.UTF-8', 03:47:18 INFO - 'LANGUAGE': 'en_US:en', 03:47:18 INFO - 'LOGNAME': 'cltbld', 03:47:18 INFO - 'MAIL': '/var/mail/cltbld', 03:47:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:18 INFO - 'MOZ_NO_REMOTE': '1', 03:47:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:18 INFO - 'NO_EM_RESTART': '1', 03:47:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:18 INFO - 'PWD': '/builds/slave/test', 03:47:18 INFO - 'SHELL': '/bin/bash', 03:47:18 INFO - 'SHLVL': '1', 03:47:18 INFO - 'TERM': 'linux', 03:47:18 INFO - 'TMOUT': '86400', 03:47:18 INFO - 'USER': 'cltbld', 03:47:18 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', 03:47:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:18 INFO - '_': '/tools/buildbot/bin/python'} 03:47:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:47:18 INFO - Cleaning up... 03:47:18 INFO - Return code: 0 03:47:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:47:18 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')]} 03:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:18 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')]} 03:47:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:18 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 0x1bca1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d93a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f36a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a090>, '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 0x1f3ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f34f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', '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 03:47:18 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 03:47:18 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 03:47:18 INFO - Using env: {'DISPLAY': ':0', 03:47:18 INFO - 'HOME': '/home/cltbld', 03:47:18 INFO - 'LANG': 'en_US.UTF-8', 03:47:18 INFO - 'LANGUAGE': 'en_US:en', 03:47:18 INFO - 'LOGNAME': 'cltbld', 03:47:18 INFO - 'MAIL': '/var/mail/cltbld', 03:47:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:18 INFO - 'MOZ_NO_REMOTE': '1', 03:47:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:18 INFO - 'NO_EM_RESTART': '1', 03:47:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:18 INFO - 'PWD': '/builds/slave/test', 03:47:18 INFO - 'SHELL': '/bin/bash', 03:47:18 INFO - 'SHLVL': '1', 03:47:18 INFO - 'TERM': 'linux', 03:47:18 INFO - 'TMOUT': '86400', 03:47:18 INFO - 'USER': 'cltbld', 03:47:18 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', 03:47:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:18 INFO - '_': '/tools/buildbot/bin/python'} 03:47:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:19 INFO - Downloading/unpacking blobuploader==1.2.4 03:47:19 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 03:47:19 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 03:47:19 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 03:47:19 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 03:47:20 INFO - Downloading blobuploader-1.2.4.tar.gz 03:47:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:47:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:47:20 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 03:47:20 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 03:47:20 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 03:47:20 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 03:47:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:47:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:47:20 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 03:47:20 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 03:47:20 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 03:47:20 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 03:47:20 INFO - Downloading docopt-0.6.1.tar.gz 03:47:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:47:20 INFO - Installing collected packages: blobuploader, requests, docopt 03:47:20 INFO - Running setup.py install for blobuploader 03:47:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:47:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:47:20 INFO - Running setup.py install for requests 03:47:21 INFO - Running setup.py install for docopt 03:47:21 INFO - Successfully installed blobuploader requests docopt 03:47:21 INFO - Cleaning up... 03:47:21 INFO - Return code: 0 03:47:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:47:21 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')]} 03:47:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:21 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')]} 03:47:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:21 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 0x1bca1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d93a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f36a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a090>, '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 0x1f3ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f34f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', '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 03:47:21 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 03:47:21 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 03:47:21 INFO - Using env: {'DISPLAY': ':0', 03:47:21 INFO - 'HOME': '/home/cltbld', 03:47:21 INFO - 'LANG': 'en_US.UTF-8', 03:47:21 INFO - 'LANGUAGE': 'en_US:en', 03:47:21 INFO - 'LOGNAME': 'cltbld', 03:47:21 INFO - 'MAIL': '/var/mail/cltbld', 03:47:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:21 INFO - 'MOZ_NO_REMOTE': '1', 03:47:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:21 INFO - 'NO_EM_RESTART': '1', 03:47:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:21 INFO - 'PWD': '/builds/slave/test', 03:47:21 INFO - 'SHELL': '/bin/bash', 03:47:21 INFO - 'SHLVL': '1', 03:47:21 INFO - 'TERM': 'linux', 03:47:21 INFO - 'TMOUT': '86400', 03:47:21 INFO - 'USER': 'cltbld', 03:47:21 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', 03:47:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:21 INFO - '_': '/tools/buildbot/bin/python'} 03:47:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:47:21 INFO - Running setup.py (path:/tmp/pip-4CJVBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:47:21 INFO - Running setup.py (path:/tmp/pip-iraMzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:47:21 INFO - Running setup.py (path:/tmp/pip-tRGlDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:47:21 INFO - Running setup.py (path:/tmp/pip-2AJaJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:47:21 INFO - Running setup.py (path:/tmp/pip-Pe_Km3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:47:22 INFO - Running setup.py (path:/tmp/pip-Tt4o4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:47:22 INFO - Running setup.py (path:/tmp/pip-BqOTqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:47:22 INFO - Running setup.py (path:/tmp/pip-zTVCsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:47:22 INFO - Running setup.py (path:/tmp/pip-awOTlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:47:22 INFO - Running setup.py (path:/tmp/pip-5cYybP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:47:22 INFO - Running setup.py (path:/tmp/pip-x6JD7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:47:22 INFO - Running setup.py (path:/tmp/pip-1SVN7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:47:22 INFO - Running setup.py (path:/tmp/pip-OOUFDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:47:22 INFO - Running setup.py (path:/tmp/pip-EvHEqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:47:22 INFO - Running setup.py (path:/tmp/pip-qrbpMH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:47:22 INFO - Running setup.py (path:/tmp/pip-B5p4dA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:47:22 INFO - Running setup.py (path:/tmp/pip-5Ole7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:47:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:47:22 INFO - Running setup.py install for manifestparser 03:47:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:47:22 INFO - Running setup.py install for mozcrash 03:47:23 INFO - Running setup.py install for mozdebug 03:47:23 INFO - Running setup.py install for mozdevice 03:47:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:47:23 INFO - Running setup.py install for mozfile 03:47:23 INFO - Running setup.py install for mozhttpd 03:47:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:47:23 INFO - Running setup.py install for mozinfo 03:47:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:47:23 INFO - Running setup.py install for mozInstall 03:47:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:47:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:47:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:47:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:47:23 INFO - Running setup.py install for mozleak 03:47:23 INFO - Running setup.py install for mozlog 03:47:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:47:23 INFO - Running setup.py install for moznetwork 03:47:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:47:23 INFO - Running setup.py install for mozprocess 03:47:24 INFO - Running setup.py install for mozprofile 03:47:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:47:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:47:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:47:24 INFO - Running setup.py install for mozrunner 03:47:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:47:24 INFO - Running setup.py install for mozscreenshot 03:47:24 INFO - Running setup.py install for moztest 03:47:24 INFO - Running setup.py install for mozversion 03:47:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:47:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:47:24 INFO - Cleaning up... 03:47:24 INFO - Return code: 0 03:47:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:47:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:24 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 0x1bca1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d93a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f36a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a090>, '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 0x1f3ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f34f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', '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 03:47:24 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 03:47:24 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 03:47:24 INFO - Using env: {'DISPLAY': ':0', 03:47:24 INFO - 'HOME': '/home/cltbld', 03:47:24 INFO - 'LANG': 'en_US.UTF-8', 03:47:24 INFO - 'LANGUAGE': 'en_US:en', 03:47:24 INFO - 'LOGNAME': 'cltbld', 03:47:24 INFO - 'MAIL': '/var/mail/cltbld', 03:47:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:24 INFO - 'MOZ_NO_REMOTE': '1', 03:47:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:24 INFO - 'NO_EM_RESTART': '1', 03:47:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:24 INFO - 'PWD': '/builds/slave/test', 03:47:24 INFO - 'SHELL': '/bin/bash', 03:47:24 INFO - 'SHLVL': '1', 03:47:24 INFO - 'TERM': 'linux', 03:47:24 INFO - 'TMOUT': '86400', 03:47:24 INFO - 'USER': 'cltbld', 03:47:24 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', 03:47:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:24 INFO - '_': '/tools/buildbot/bin/python'} 03:47:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:47:24 INFO - Running setup.py (path:/tmp/pip-NYE9a3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:47:24 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)) 03:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:47:24 INFO - Running setup.py (path:/tmp/pip-_lXzYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:47:24 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)) 03:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:47:24 INFO - Running setup.py (path:/tmp/pip-uOM7QD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:47:25 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)) 03:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:47:25 INFO - Running setup.py (path:/tmp/pip-k_xXv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:47:25 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)) 03:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:47:25 INFO - Running setup.py (path:/tmp/pip-8I6I7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:47:25 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)) 03:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:47:25 INFO - Running setup.py (path:/tmp/pip-LyG4DR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:47:25 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)) 03:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:47:25 INFO - Running setup.py (path:/tmp/pip-6CMXlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:47:25 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)) 03:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:47:25 INFO - Running setup.py (path:/tmp/pip-RzmhTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:47:25 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)) 03:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:47:25 INFO - Running setup.py (path:/tmp/pip-QGY4bp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:47:25 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)) 03:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:47:25 INFO - Running setup.py (path:/tmp/pip-uuCB2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:47:25 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)) 03:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:47:25 INFO - Running setup.py (path:/tmp/pip-SQl0kF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:47:25 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)) 03:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:47:25 INFO - Running setup.py (path:/tmp/pip-rOjIfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:47:25 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)) 03:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:47:25 INFO - Running setup.py (path:/tmp/pip-rB6xLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:47:25 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)) 03:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:47:25 INFO - Running setup.py (path:/tmp/pip-WlSt2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:47:25 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)) 03:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:47:25 INFO - Running setup.py (path:/tmp/pip-ZydzQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:47:25 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)) 03:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:47:25 INFO - Running setup.py (path:/tmp/pip-yj0ATb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:47:26 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)) 03:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:47:26 INFO - Running setup.py (path:/tmp/pip-hAP4BW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:47:26 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)) 03:47:26 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)) 03:47:26 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)) 03:47:26 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)) 03:47:26 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)) 03:47:26 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)) 03:47:26 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)) 03:47:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:47: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 03:47: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 03:47: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 03:47: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 03:47:27 INFO - Downloading blessings-1.6.tar.gz 03:47:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:47:27 INFO - Installing collected packages: blessings 03:47:27 INFO - Running setup.py install for blessings 03:47:27 INFO - Successfully installed blessings 03:47:27 INFO - Cleaning up... 03:47:27 INFO - Return code: 0 03:47:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:47:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:47:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:47:27 INFO - Reading from file tmpfile_stdout 03:47:27 INFO - Current package versions: 03:47:27 INFO - argparse == 1.2.1 03:47:27 INFO - blessings == 1.6 03:47:27 INFO - blobuploader == 1.2.4 03:47:27 INFO - docopt == 0.6.1 03:47:27 INFO - functools32 == 3.2.3-2 03:47:27 INFO - jsonschema == 2.5.1 03:47:27 INFO - manifestparser == 1.1 03:47:27 INFO - mozInstall == 1.13 03:47:27 INFO - mozcrash == 1.0 03:47:27 INFO - mozdebug == 0.1 03:47:27 INFO - mozdevice == 0.51 03:47:27 INFO - mozfile == 1.2 03:47:27 INFO - mozhttpd == 0.7 03:47:27 INFO - mozinfo == 0.10 03:47:27 INFO - mozleak == 0.1 03:47:27 INFO - mozlog == 3.5 03:47:27 INFO - moznetwork == 0.27 03:47:27 INFO - mozprocess == 0.25 03:47:27 INFO - mozprofile == 0.28 03:47:27 INFO - mozrunner == 6.13 03:47:27 INFO - mozscreenshot == 0.1 03:47:27 INFO - mozsystemmonitor == 0.3 03:47:27 INFO - moztest == 0.8 03:47:27 INFO - mozversion == 1.4 03:47:27 INFO - psutil == 3.1.1 03:47:27 INFO - requests == 1.2.3 03:47:27 INFO - wsgiref == 0.1.2 03:47:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:47:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:27 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 0x1bca1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d93a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f36a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a090>, '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 0x1f3ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f34f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', '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 03:47:27 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 03:47:27 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 03:47:27 INFO - Using env: {'DISPLAY': ':0', 03:47:27 INFO - 'HOME': '/home/cltbld', 03:47:27 INFO - 'LANG': 'en_US.UTF-8', 03:47:27 INFO - 'LANGUAGE': 'en_US:en', 03:47:27 INFO - 'LOGNAME': 'cltbld', 03:47:27 INFO - 'MAIL': '/var/mail/cltbld', 03:47:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:27 INFO - 'MOZ_NO_REMOTE': '1', 03:47:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:27 INFO - 'NO_EM_RESTART': '1', 03:47:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:27 INFO - 'PWD': '/builds/slave/test', 03:47:27 INFO - 'SHELL': '/bin/bash', 03:47:27 INFO - 'SHLVL': '1', 03:47:27 INFO - 'TERM': 'linux', 03:47:27 INFO - 'TMOUT': '86400', 03:47:27 INFO - 'USER': 'cltbld', 03:47:27 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', 03:47:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:27 INFO - '_': '/tools/buildbot/bin/python'} 03:47:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:27 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)) 03:47:27 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)) 03:47:27 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)) 03:47:27 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)) 03:47:27 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)) 03:47:27 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)) 03:47:27 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)) 03:47:27 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)) 03:47:27 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)) 03:47:27 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:47:29 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)) 03:47:29 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)) 03:47:29 INFO - Installing collected packages: simplejson 03:47:29 INFO - Running setup.py install for simplejson 03:47:29 INFO - building 'simplejson._speedups' extension 03:47:29 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 03:47:29 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 03:47:30 INFO - Successfully installed simplejson 03:47:30 INFO - Cleaning up... 03:47:30 INFO - Return code: 0 03:47:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:47: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')]} 03:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47: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')]} 03:47:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bca1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d93a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f36a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a090>, '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 0x1f3ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f34f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', '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 03:47: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'] in /builds/slave/test/build 03:47: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 03:47:30 INFO - Using env: {'DISPLAY': ':0', 03:47:30 INFO - 'HOME': '/home/cltbld', 03:47:30 INFO - 'LANG': 'en_US.UTF-8', 03:47:30 INFO - 'LANGUAGE': 'en_US:en', 03:47:30 INFO - 'LOGNAME': 'cltbld', 03:47:30 INFO - 'MAIL': '/var/mail/cltbld', 03:47:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:30 INFO - 'MOZ_NO_REMOTE': '1', 03:47:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:30 INFO - 'NO_EM_RESTART': '1', 03:47:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:30 INFO - 'PWD': '/builds/slave/test', 03:47:30 INFO - 'SHELL': '/bin/bash', 03:47:30 INFO - 'SHLVL': '1', 03:47:30 INFO - 'TERM': 'linux', 03:47:30 INFO - 'TMOUT': '86400', 03:47:30 INFO - 'USER': 'cltbld', 03:47:30 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', 03:47:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:30 INFO - '_': '/tools/buildbot/bin/python'} 03:47:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:47:30 INFO - Cleaning up... 03:47:30 INFO - Return code: 0 03:47:30 INFO - Running post-action listener: _resource_record_post_action 03:47:30 INFO - Running post-action listener: _start_resource_monitoring 03:47:30 INFO - Starting resource monitoring. 03:47:30 INFO - [mozharness: 2017-09-11 10:47:30.321438Z] Finished create-virtualenv step (success) 03:47:30 INFO - [mozharness: 2017-09-11 10:47:30.321950Z] Running install step. 03:47:30 INFO - Running pre-action listener: _resource_record_pre_action 03:47:30 INFO - Running main action method: install 03:47:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:47:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:47:30 INFO - Reading from file tmpfile_stdout 03:47:30 INFO - Detecting whether we're running mozinstall >=1.0... 03:47:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:47:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:47:30 INFO - Reading from file tmpfile_stdout 03:47:30 INFO - Output received: 03:47:30 INFO - Usage: mozinstall [options] installer 03:47:30 INFO - Options: 03:47:30 INFO - -h, --help show this help message and exit 03:47:30 INFO - -d DEST, --destination=DEST 03:47:30 INFO - Directory to install application into. [default: 03:47:30 INFO - "/builds/slave/test"] 03:47:30 INFO - --app=APP Application being installed. [default: firefox] 03:47:30 INFO - mkdir: /builds/slave/test/build/application 03:47:30 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'] 03:47:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:47:47 INFO - Reading from file tmpfile_stdout 03:47:47 INFO - Output received: 03:47:47 INFO - /builds/slave/test/build/application/firefox/firefox 03:47:47 INFO - Running post-action listener: _resource_record_post_action 03:47:47 INFO - [mozharness: 2017-09-11 10:47:47.070856Z] Finished install step (success) 03:47:47 INFO - [mozharness: 2017-09-11 10:47:47.071110Z] Running setup-mitmproxy step. 03:47:47 INFO - Running pre-action listener: _resource_record_pre_action 03:47:47 INFO - Running main action method: setup_mitmproxy 03:47:47 INFO - Skipping: mitmproxy is not required 03:47:47 INFO - Running post-action listener: _resource_record_post_action 03:47:47 INFO - [mozharness: 2017-09-11 10:47:47.072378Z] Finished setup-mitmproxy step (success) 03:47:47 INFO - [mozharness: 2017-09-11 10:47:47.072598Z] Running run-tests step. 03:47:47 INFO - Running pre-action listener: _resource_record_pre_action 03:47:47 INFO - Running pre-action listener: _set_gcov_prefix 03:47:47 INFO - Running main action method: run_tests 03:47:47 WARNING - Try message not found. 03:47:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:47:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:47:47 INFO - Python 2.7.3 03:47:47 INFO - Return code: 0 03:47:47 INFO - grabbing minidump binary from tooltool 03:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:47 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 0x1f3a090>, '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 0x1f3ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f34f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:47:47 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 03:47:47 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 03:47:47 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 03:47:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:47:47 INFO - Return code: 0 03:47:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:47:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:47:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:47:47 INFO - ENV: RUST_BACKTRACE is now full 03:47:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:47:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:47:47 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/XZ_hBdiHR7SWWTd1kz6MXA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-087', '--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 03:47:47 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/XZ_hBdiHR7SWWTd1kz6MXA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-087 --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 03:47:47 INFO - Using env: {'DISPLAY': ':0', 03:47:47 INFO - 'HOME': '/home/cltbld', 03:47:47 INFO - 'LANG': 'en_US.UTF-8', 03:47:47 INFO - 'LANGUAGE': 'en_US:en', 03:47:47 INFO - 'LOGNAME': 'cltbld', 03:47:47 INFO - 'MAIL': '/var/mail/cltbld', 03:47:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:47:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:47:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:47 INFO - 'MOZ_NO_REMOTE': '1', 03:47:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:47:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:47 INFO - 'NO_EM_RESTART': '1', 03:47:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:47 INFO - 'PWD': '/builds/slave/test', 03:47:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:47:47 INFO - 'RUST_BACKTRACE': 'full', 03:47:47 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:47:47 INFO - 'SHELL': '/bin/bash', 03:47:47 INFO - 'SHLVL': '1', 03:47:47 INFO - 'TERM': 'linux', 03:47:47 INFO - 'TMOUT': '86400', 03:47:47 INFO - 'USER': 'cltbld', 03:47:47 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', 03:47:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:47 INFO - '_': '/tools/buildbot/bin/python'} 03:47:47 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/XZ_hBdiHR7SWWTd1kz6MXA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-087', '--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 03:47:47 INFO - mozversion application_buildid: 20170911091903 03:47:47 INFO - mozversion application_changeset: f9a5e9ed62103c84e4cde915f4d08f1ce71be83e 03:47:47 INFO - mozversion application_display_name: Nightly 03:47:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:47:47 INFO - mozversion application_name: Firefox 03:47:47 INFO - mozversion application_remotingname: firefox 03:47:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:47:47 INFO - mozversion application_vendor: Mozilla 03:47:47 INFO - mozversion application_version: 57.0a1 03:47:47 INFO - mozversion platform_buildid: 20170911091903 03:47:47 INFO - mozversion platform_changeset: f9a5e9ed62103c84e4cde915f4d08f1ce71be83e 03:47:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:47:47 INFO - mozversion platform_version: 57.0a1 03:47:47 INFO - using testdate: 1505126867 03:47:47 INFO - actual date: 1505126867 03:47:47 INFO - starting webserver on 'localhost:36353' 03:47:47 INFO - SUITE-START | Running 2 tests 03:47:47 INFO - TEST-START | dromaeo_css 03:47:47 INFO - Initialising browser for dromaeo_css test... 03:47:47 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36353/getInfo.html -profile /tmp/tmpLLjmhd/profile 03:47:47 INFO - TEST-INFO | started process 21818 (/builds/slave/test/build/application/firefox/firefox http://localhost:36353/getInfo.html) 03:47:53 INFO - TEST-INFO | 21818: exit 0 03:47:53 INFO - Browser initialized. 03:47:53 INFO - Running cycle 1/1 for dromaeo_css test... 03:47:53 INFO - Using env: {'DISPLAY': ':0', 03:47:53 INFO - 'HOME': '/home/cltbld', 03:47:53 INFO - 'JSGC_DISABLE_POISONING': '1', 03:47:53 INFO - 'LANG': 'en_US.UTF-8', 03:47:53 INFO - 'LANGUAGE': 'en_US:en', 03:47:53 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:47:53 INFO - 'LOGNAME': 'cltbld', 03:47:53 INFO - 'MAIL': '/var/mail/cltbld', 03:47:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:47:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:47:53 INFO - 'MOZ_CRASHREPORTER': '1', 03:47:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:53 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:47:53 INFO - 'MOZ_NO_REMOTE': '1', 03:47:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:47:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:53 INFO - 'NO_EM_RESTART': '1', 03:47:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:53 INFO - 'PWD': '/builds/slave/test', 03:47:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:47:53 INFO - 'RUST_BACKTRACE': 'full', 03:47:53 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:47:53 INFO - 'SHELL': '/bin/bash', 03:47:53 INFO - 'SHLVL': '1', 03:47:53 INFO - 'TERM': 'linux', 03:47:53 INFO - 'TMOUT': '86400', 03:47:53 INFO - 'USER': 'cltbld', 03:47:53 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', 03:47:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:53 INFO - '_': '/tools/buildbot/bin/python'} 03:47:53 INFO - TEST-INFO | started process 22021 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLLjmhd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:47:54 INFO - PID 22021 | 03:47:54 INFO - PID 22021 | (/builds/slave/test/build/application/firefox/firefox:22073): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:47:54 INFO - PID 22021 | 03:48:04 INFO - PID 22021 | RSS: Main: 164569088 03:48:04 INFO - PID 22021 | 03:51:01 INFO - PID 22021 | Cycle 1(1): loaded http://localhost:36353/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36353/tests/dromaeo/cssquery-ext.html) 03:51:02 INFO - PID 22021 | RSS: Main: 168050688 03:51:02 INFO - PID 22021 | 03:54:03 INFO - PID 22021 | Cycle 1(1): loaded http://localhost:36353/tests/dromaeo/cssquery-ext.html (next: http://localhost:36353/tests/dromaeo/cssquery-jquery.html) 03:54:04 INFO - PID 22021 | RSS: Main: 164544512 03:54:04 INFO - PID 22021 | 03:57:06 INFO - PID 22021 | Cycle 1(1): loaded http://localhost:36353/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36353/tests/dromaeo/cssquery-mootools.html) 03:57:06 INFO - PID 22021 | RSS: Main: 166649856 03:57:06 INFO - PID 22021 | 04:00:08 INFO - PID 22021 | Cycle 1(1): loaded http://localhost:36353/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36353/tests/dromaeo/cssquery-prototype.html) 04:00:08 INFO - PID 22021 | RSS: Main: 165564416 04:00:08 INFO - PID 22021 | 04:03:11 INFO - PID 22021 | Cycle 1(1): loaded http://localhost:36353/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36353/tests/dromaeo/cssquery-yui.html) 04:03:12 INFO - PID 22021 | RSS: Main: 166731776 04:03:12 INFO - PID 22021 | 04:06:13 INFO - PID 22021 | Cycle 1(1): loaded http://localhost:36353/tests/dromaeo/cssquery-yui.html (next: http://localhost:36353/tests/dromaeo/cssquery-dojo.html) 04:06:14 INFO - PID 22021 | RSS: Main: 169107456 04:06:14 INFO - PID 22021 | 04:06:14 INFO - PID 22021 | __start_tp_report 04:06:14 INFO - PID 22021 | _x_x_mozilla_page_load 04:06:14 INFO - PID 22021 | _x_x_mozilla_page_load_details 04:06:14 INFO - PID 22021 | |i|pagename|runs| 04:06:14 INFO - PID 22021 | |0;dojo.html;3890.8327501749477;3893.3770596704517;3897.415387691844;3912.3935789952548;3922.666573341264;6205.658688772116;6257.155284036654;6322.968385158074;6331.715072821723;6411.455026322762;6321;6324.083007963843;6331.556791024618;6411.711472983727;6435.6782160892;5576.275084239043;5681.375048744635;5682.744276507547;5697.116946873241;5699.316082070158;265.8729865660288;5380.91928621071;5643.4074422185695;5672.744726487297;5679.829605111832;5574.191742197399;5596.748146333425;5657;5683.374828768832;5702.543796496312;5606.6636001839615;5635.042042852725;5698.287714035746;5704.2299289595785;5711.571632127607;5627.662340259557;5629.4652008059165;5694.5159661428415;5697.116946873241;5698.544116470714;6069.482629342664;6234.688265586702;6296.811095667092;6322.430981211667;6335.271443783978;6743.426808721265;6815.114035175396;6825.453963682893;6831.351021652985;6834.350736680008;6885.277045910208;6916.273791251947;6995.8950615740805;6996.790096297119;7002.299770023008;6393.9360606394275;6424.4860411167465;6461.1600491936215;6475.5467117301805;6488.091667166554;7081.185663648644;7184.425245980309;7190.7483238086415;7209.026781384446;7211.2428195038765;6813.284605116491;6815.2843951385385;6885.552439091443;6889.827754306182;6895;6820.181578210547;6897.448204143657;6899.48253880961;6942.548734332353;6984.546004509691;6829.078074459983;6843.520953533304;6854.862902741917;6858.5199036068;6876.553024053421;6702.229243636946;6784.287649796799;6877.518573699893;6882.827929301808;6886.380225779704;4967.652264341461;4969.726665033458;5027.924581131286;5036.496350364934;5038.84883453497;4521.59305662492;4568.862934111982;4605.378273933044;4613.907721845544;4655.208614535537;6327.050942358683;6332.1135041094285;6389.488840892717;6414.518911081687;6415.133956915756;6877.346652068046;6879.621620810904;6931.168259808755;6949.652517374111;6967;6249.812505624838;6312.116303717483;6339.461145802658;6371.108044873721;6381.585196962183;5549.1676248562235;5607.691576963224;5614.129809879475;5618.353889302701;5622.156676498558;7146.964265178744;7166;7240.637968101679;7254.782356529313;7275.345218930322;6784.966075169593;6836.863262734817;6866.519343645897;6876.931230687629;6878.312168783082;7079.2566780487305;7143.607101609254;7158.749443769443;7164.498485105993;7172.964135179291;6673.265940746417;6749.223839258548;6759.053732477359;6825.590464572141;6835.897461537981;6870.690818913261;6973.860522789414;6983.650817459208;6984.196817366068;6984.930150698631;6614.735410583523;6639.2364878037715;6673.332666733288;6713.026611141259;6738.124043874265;6495.9025614614875;6584.275729669636;6588.143541339595;6616.470682345497;6699.43054840344;6601.9009714853355;6661.666916654245;6664.7000884959325;6664.766733164316;6668.266490686117;6674.733010679518;6676.83307917306;6682.298358672464;6720.798376048726;6770.255271919987;6334.588251763621;6411.647359395091;6414.198225221848;6460.224773027079;6572.704228309642 04:06:14 INFO - PID 22021 | |1;ext.html;896.9955150224253;899.5325380089366;925.0379605210582;952.0099096939183;956.0105291023792;43550;44592.3311150333;44712.3293150603;44738.55261447385;44765.55234447654;41796.791016044954;41800;41805.16389672205;42215;42257.78871105648;7379.52033117847;7479.065116860393;7479.62601869907;7482.027336446282;7482.625868706543;7322.48742588019;7407.851842963138;7409.14794798597;7419.777406677808;7436.590987495686;8066.153053929341;8302.086770455246;8307.252347288773;8307.792305192359;8312.1687831217;8216.465929714579;8277.95861020691;8284.047334556542;8284.917150828474;8290.544020078893;7878.054633444023;8137.226963438464;8141.145179756099;8141.470804397754;8170.468919520212;40804.38793418101;41286.79356603198;41290.38064429036;41317.79341103305;41330;3704.7962362070075;3756.455313979485;3756.549214094299;3757.605451427588;3757.8684746034032;33454.498182527284;33520.32959340824;33544.16139596505;33566.83216583926;33692.83153584216;5478.945210547883;5501.229827824108;5520.613557051008;5524.392316845144;5561.972190139064;218589;219052;219260;220594;220698.9999999984;51342.22986655203;51565.742171288905;51567.48432515627;51748.22377664338;51750;53964.730176348865;54014;54039.729801351525;54089.8364065433;54152;15645.530634080997;15893.284802183929;15907.204639767966;15953.521394358188;15972.600684982968;12714.110012299236;12726.554570589984;12744.490220391266;12747.298898560482;12790.488380464678;15039;15263.389464421522;15332;15369;15394;5985.85216713626;6033.698315084228;6058.818235452944;6087.634741915499;6316.747330106836;1072.8658917635296;1080.2276372393776;1113.5267511307825;1117.3519358771894;1119.2445099557765;25564.360890977874;25646.487070258863;25651.871740641178;25864.8706756465;25953.74046259551;1195.468016732563;1204.987950120505;1208.6434501821984;1214.623466725318;1228.5147366790068;13538.187708737507;13604.863951360558;13667.111637743516;13678.589642310746;13761;293115;293438;293934;293958;294732;56476.43523564712;60533.69733151306;61296.693516532134;61305;61322.386776131665;1472.9410823566936;1498.1834899979538;1508.4644951042442;1518.2484670088377;1533.7392643250673;29222.853885730434;29277.121686349445;30005.399892002475;30305.393892122476;30382.696173037988;40359.192816142924;40537.59462405338;40585.59414405936;40601.79699101485;40701.796491017354;249086;249905;250236;250634;250672;34863.30273394569;34975;35083.122921927155;35089.1227719309;35143;45098.774506128575;45123;45164.77417612891;45281.77359113183;45484.545154549356;34163.65836341605;34251.82874085614;34272.31455370829;34553.654463455045;34717.47923781196;34073.82963085169;34093.31813363769;34370;34389.828050859585;34720.479192812636;39736.403953940135;39784.80107599444;40284.59715402926;40335;40609;32837.83581082079;33223.66776332206;33293.50059749057;33501.32997340088;33810.49284260689;25278.368040798392;25426.74573254318;25498.457452650375;25681.22956311314;25725.228243152735 04:06:14 INFO - PID 22021 | |2;jquery.html;598.5191381722758;599.802065318445;611.126089691741;617.6973283091281;624.0825985800873;23291.883540582294;23876.88061559694;24055.39861503461;24081.879590602046;24085.879570602166;24203.03187872483;26331.736682633167;26558.734412655867;26587.202383928463;26619.068332608364;6436.678166091689;6534.150560427138;6566.901496477557;6571.901421478682;6580.901286480706;6441.8711625767455;6481.09264702942;6730.5625134366355;6739.561928474658;6741.224759152711;6716.764913228038;6726.058351830723;6727.596344219338;6729.7308107675635;6730.394264516192;6495.740170393178;6672;6690.063391125246;6717.8992315115065;6778.457723382117;6333.809985700436;6336.904946425807;6339.651319177444;6349.904751428732;6356.364363563653;30772.53841192384;31160.37679246425;31167.68832311693;31192.220194495094;31219.531707024413;2257.548490301946;2273.4771002669477;2277;2278.2823410625765;2281.931542053741;20651.27720529774;21039.8948005259;21087;21110.944452777454;21146.788532114635;2569.9486010279875;2604.075553178613;2653.6815582130266;2660.255128564005;2667.9199624011117;133241;141373;148256;155674;156311.21844390818;16045.438409655839;16626.33494660224;16648.916755416387;16856;16878.240479178465;25974.22077337683;25999.480010399686;26040.479190416085;26042.609360859602;26057.73942260591;10455.68632941013;10598.417087060132;10600.893991060146;10654.094401975764;10716.571337146426;9439.622415103318;9459.10138536838;9532.428054316762;9595.42427454341;9609.37539059973;9891.406515609087;10008.799824003625;10032.197424206046;10102.797944041078;10141.797164056678;2879.985600071986;2922.678505364408;2923.9415211696073;2925.546540286259;3038.9848050759606;1029.1509504658686;1047.701405099543;1056.7199692081604;1060.204846365226;1061.4162210784193;21493;22019.339419817432;22063.558728825654;22239.666405003936;22296.21963231279;1097.6048622495896;1113.8050841102774;1118.9384583847805;1121.0527104596604;1128.90968722502;11736.647900562997;11908.761824763456;11940.701482463008;12044.51821927113;12121.212121212093;643615;656770;658792;664472;675303;133963;135631;135922;135972;140039;1201.8077107662732;1222.0529089955178;1225.681322856046;1247.457356050121;1252.6868282929267;18452.723209152144;18802.6239475207;18998.905005475437;19077.90461047686;19309.937953412697;62558.6872065655;62788.37211628008;63306.36693633189;63936.36063639304;65417.672911635134;76938.61530692535;77931.61034194793;78074;78103;78798;15431.61420964485;15885.046897186206;15921.203939802732;15975.520734377988;16000.279987400361;24967.750322496544;25153.87423062873;25264.23150379456;25447;25517.489650207262;19914.20343186314;20250.189992400137;20341.389758307276;20379.898100509403;20380.59238815245;16393;16906.239219234918;17442.302307907543;17635.82364176393;17860.464186074438;20200.090995905513;20476.590468190254;20498.79501204969;20648.58702825965;20788;15575.688486230438;15580.220988950281;15728.92135539315;15735;15745.291461884013;19762.802371976097;20079.69880451765;20103.59792804165;20264.08811603452;20293.695594565797 04:06:14 INFO - PID 22021 | |3;mootools.html;1366.3168415792104;1461.6930444606633;1468.6548661064655;1494.7608382658773;1501.2643804535774;16328.428505002312;16867;16868.831311686896;16871.240794164292;16964.236609352578;16564;16645.916770416163;16710.749338759928;16835.242414091394;16868.578285542866;2765.5575107982727;2775.680796708374;2829.4058247767994;2829.745322920937;2833.135893552466;2741.821781584191;2777.347323379004;2787.3310405502734;2793.9580906286424;2808.087371604237;2639.155470249521;2736.58951157327;2851.3441908361087;2852.101587999784;2860.4994126027864;2786.192004318745;2795.35706787439;2798.916032519028;2817.7323154300307;2823.759980401668;2750.436160587095;2760.4341110072387;2776.3753155540044;2779;2791.3579876628487;16281.755773663404;16632.750508742378;16673.83326166735;16707.916460417742;16773.412930547493;7836.882446763303;8118.309943654737;8118.837623247561;8129.065157506903;8141.877871831927;5319.627626066177;5368.4631536846;5478.479544443311;5525.972370138164;5651;2065.659166237569;2072.4818795301176;2075.6886467029917;2088.4778805298674;2142.5607750411127;29738.851305743334;29922.25194370158;29970;30003.099907002827;30003.849980749957;6987.7204911802955;7027.15674119109;7067.469939754539;7157.749478768218;7198.100237470317;19728.90135549313;19735.802641973398;19756;19838.50403739918;19838.70241946372;8378.622961966605;8379.413441059067;8381.287590554872;8383.832323353621;8390.454620449651;7640.923590764132;7655.234476552411;7739.1873853245725;7740.8838867417035;7744.148143704188;5671.688057156896;5813.04084825999;5828.6502809831545;5873.559483038789;5885.234919460443;1560.8985415947927;1595.5133684226423;1634.0685809088943;1655.7350823868123;1680.638662687528;952.1573407534244;996.4469719305855;997.3168379659957;1002.4185972136289;1006.4766321512918;9207.723768286962;9376.95311523438;9633.9036609633;9643.903560964442;9741.025897410202;812.2567850416921;883.7127933421664;883.9204471597587;1081.6538707613488;1092.3882625729616;5930.495907847795;5933.703314834241;5956.821295361146;6028.457438830438;6066.1204125402455;23058.077676893074;23111.7688823113;23984.88007559951;24088.397790055387;25399.746002539738;25326;25386;25409.872950635498;25534.361640958752;25727;11423.942880285545;12370.505179792888;12627.179233349803;12661.050421218446;12744.10791244604;9550.55619294652;9645.855312170183;9734.805303894023;9818.410895346016;9906;20893.58212835765;21066.157353706294;21839.454013649785;21883.999999999363;22094.22670206535;21670.566588668455;21862.032417081904;22002;22216;22218.888905556014;12109;12343.567975120828;12350.691232719253;12351.629451116121;12352.62942111738;15428.537143885702;15450.690986179989;15646.061236325857;15731.528054158394;15776.368945242062;12607.243565386107;12634.178778379375;12666.683332916382;13030.804537931748;13144.408501617641;12408.937955310166;12498.937505312415;12504.249745015328;12561.87438125607;12818;9703;9743.707688769347;9759.804803904024;9917.404955702681;9925.1067403934;7893.526388416483;7925.841483170419;7926.128125906031;8016.9198308016175;8096.919030809617;8093.35253179756;8129.75610731679;8188.058373287148;8190.959045204736;8305.46014509055 04:06:14 INFO - PID 22021 | |4;prototype.html;544.8419958212118;547.5181839980817;571.4028839862343;577.9104238842975;587.2014060877207;6153.35389784073;6243.812685619428;6266.62400255985;6320.914098347545;6392.872142557146;6106;6156.938430615693;6160.383961603838;6188.690565471731;6209.844753881155;5071.644984851062;5106.336176297076;5127.256547800566;5141.357330333708;5158.9484105158845;4885.949520853016;4951.876203094916;5076.746162691869;5117.616178786588;5165.767540460688;4901.142300097486;4912.164931961575;4941.579965702919;4975.27858460523;5031.396232452111;4906.607471402279;4929.383827021622;4940.752962351868;5001.7499125043605;5032.892763592004;4411.977940110311;4484.125595508877;4498.537975158054;4544.182047231497;4563.224251877189;5974.402559744034;6089.421504957022;6096.7561297548045;6113.602615830001;6153.569250152491;5688.094488346409;5713.628614140068;5788.131780232957;5788.88422231553;5799.855003624901;6504.699060187982;6540.37866402691;6580.473562115068;6627.801165965029;6632.900506492407;4767.928481072787;4998.775055122528;5033.7734801934;5043;5069.467705890866;6630.403263706289;6748.662566871637;6875.140607424072;6886.51794374394;6900.3444672756;5016.498350164954;5042.3444952156715;5055.573324804114;5107.668001579886;5112.769925353368;5075.187969924795;5115.667481613683;5150.227465880073;5152.098382783073;5167.1474206755465;5694.743736531866;5695.259616249944;5722.313322401338;5730.312362516524;5740.339860915965;6553.442957348583;6620.3710647488415;6620.933790662128;6703.363180497845;6717.9664101680155;1765.7174852023616;1791.9014454205144;1795.231696778672;1827.470033690215;1830.1398342778873;1225.8896699297106;1288.3880156925572;1293.1982171054008;1293.2822283632486;1297.7923532234797;6054.152418661391;6215.34738852423;6216.2540495140875;6237.189165408558;6241.563090583615;6454.870902581922;6626.933730662631;6698.33016698336;6724.462043036546;6798.966005169942;6014;6057.7879774207695;6072.787452439144;6117.694115294217;6132.264128304543;3486.511888335635;3494.5282386877952;3495.178633021238;3538.433850583894;3563.2517171394297;6790.898136527956;6831.28271531482;6934.895976560356;6988.6855091521;7052.9647351762915;6775.593464392152;6783.320588367567;6811.693473793691;6848.315168483112;6921.169459664772;6846.794596162223;6903.861922761617;6938.167419909542;6961.860762784817;7060.916140378919;6688.331166883273;6712.362325579063;6714.597124172566;6716.3955244028275;6756.560823546453;6573.40839324444;6578.638174900427;6601.966990165019;6668.932291146127;6696.899546506904;6865.931340686729;6887.552309099892;6902.8619427610165;6960.39910820504;7021.262767409348;5167;5169.405518365436;5177.663451875616;5200.349956255468;5211.869703257297;6396.584222025554;6587.571807832475;6705.228898676714;6733;6818.8636227276165;6421.614703117828;6477.222733272038;6488.870222595616;6512.413882750386;6524.347565243438;6406.391392817675;6479.41685248311;6484.351564843667;6533.477321814338;6570.408663220333;6215.906761398673;6327.240731112296;6381.170447841937;6463.515236357198;6505.544611877123;6497.935020649733;6516.543841931019;6596.538242323183;6608.339166083353;6627.933720662924;6221.844453888508;6288.622682638873;6300.487882908161;6360.840978975378;6388.955534229188 04:06:14 INFO - PID 22021 | |5;yui.html;996.7159359582519;1011.2516737614168;1013.9036791504805;1015.8984101589838;1023.708243150702;19154.90422547887;19545.21819127235;19564.804351956493;19598.31405900794;20140.899295503503;18258.17838197281;18301.90849045756;18338;18363.816361836376;18408;1000.8498725191225;1011.3729487717625;1014.8477728340735;1015.5125539740928;1015.7155996321005;1050.3067975136412;1082.123479981214;1083.756154865157;1085.1752667972357;1097.067492631261;1138.2032577195996;1160.025578514048;1160.1530882455813;1160.257435241446;1160.5995931403652;1106.286445242814;1141.5091510650418;1145.34715212329;1148.299537282253;1161.3206274329527;808.6684459371688;826.8098337382436;831.8003679117029;832.309183377795;834.7245409015011;21091.578168436547;21218.893905530527;21238.469038274012;21250.362489125197;21273.787262127335;19544.902275488676;19728.309509167255;19948.601027979505;19964.80035199644;20235.291764788162;17429.564260893454;17537.298508059663;18122.546936326566;18215.08924553767;18237.63524729511;3872.941905871386;3901.1222474943193;3901.2977664020464;3903.609639036102;3911.159100793343;143330.28334858365;147157.26421367933;148112.51887481095;148847;148959;15484.92257538705;15496.92251538735;15672.216389180496;15687.450939217073;15710.371585136696;31042.68957310398;32389.676103238668;32588.67411325904;32655.346893062007;32696.346073078406;8754.124587541195;8889.599968001456;8931.19619234259;8997.730068097968;9024.8646270306;4314.22343978087;4387.824487020547;4401.867943961686;4402.163588918095;4431.468223813163;6171.13604095427;6277.340879207709;6558.245801732766;6729.831754206086;6738.561993470506;1217.4582310871754;1238.1147479552164;1259.483611719209;1262.9936850315873;1263.7472505498938;757.9052618422697;772.3319328780565;781.9452638315263;788.3929374381694;794.1859593916282;12989.740205195843;14008.299585020708;14304.928475357556;14315.355808988621;14632.048916820373;676.9086173366533;685.7908337956871;692.6848284030754;695.8295217671703;697.5152269172876;6554.180727409074;6569.37590928861;6602.966985165043;6640.236372817188;6662.6668666566475;144959;148405;149246;150732;154597.22701386645;128586;129631.35184324018;130628;133522.33238833744;134949;110843;111516.44241778902;113168;113538;116030;12332.321722305;12366.505339786308;12379.381030948596;12407.473034285327;12592.559260425842;55490.44509554853;55640.16539751988;55895.16157257726;56213.718931405085;57825.421745783686;71426.64286678533;71780.28219717945;71909.6404517974;73239;75996.62001689956;17621.383251586132;17761.401473867412;17799.822001780336;17937;17951.640967180843;18929.148188331284;19252.518687032938;19413.1264093119;19474.707879382106;19555.902220488806;18492.35276765307;18561.16474758666;18664.440066797473;18789.248430062646;18888.52778680489;18403.447896563124;18482.630347393246;18542.44372668822;18546.81453185505;18620.068996549846;17230.224639891487;17267.568310792332;17349.392771252944;17527.123643817504;17563.209655565275;13285.269310188032;13352;13355.799663005257;13403.530876419278;13472.191668499921;13751.449942002208;13904.165750055028;13969.161850289016;14121.2233327168;14224.857751422353 04:06:14 INFO - PID 22021 | __end_tp_report 04:06:14 INFO - PID 22021 | __start_cc_report 04:06:14 INFO - PID 22021 | _x_x_mozilla_cycle_collect,348 04:06:14 INFO - PID 22021 | __end_cc_report 04:06:14 INFO - PID 22021 | __startTimestamp1505127974114__endTimestamp 04:06:14 INFO - PID 22021 | 04:06:14 INFO - PID 22021 | ------- Summary: start ------- 04:06:14 INFO - PID 22021 | Number of tests: 6 04:06:14 INFO - PID 22021 | 04:06:14 INFO - PID 22021 | [#0] dojo.html Cycles:165 Average:6293.41 Median:6588.14 stddev:897.13 (13.6%) stddev-sans-first:879.85 04:06:14 INFO - PID 22021 | Values: 3890.8 3893.4 3897.4 3912.4 3922.7 6205.7 6257.2 6323.0 6331.7 6411.5 6321.0 6324.1 6331.6 6411.7 6435.7 5576.3 5681.4 5682.7 5697.1 5699.3 265.9 5380.9 5643.4 5672.7 5679.8 5574.2 5596.7 5657.0 5683.4 5702.5 5606.7 5635.0 5698.3 5704.2 5711.6 5627.7 5629.5 5694.5 5697.1 5698.5 6069.5 6234.7 6296.8 6322.4 6335.3 6743.4 6815.1 6825.5 6831.4 6834.4 6885.3 6916.3 6995.9 6996.8 7002.3 6393.9 6424.5 6461.2 6475.5 6488.1 7081.2 7184.4 7190.7 7209.0 7211.2 6813.3 6815.3 6885.6 6889.8 6895.0 6820.2 6897.4 6899.5 6942.5 6984.5 6829.1 6843.5 6854.9 6858.5 6876.6 6702.2 6784.3 6877.5 6882.8 6886.4 4967.7 4969.7 5027.9 5036.5 5038.8 4521.6 4568.9 4605.4 4613.9 4655.2 6327.1 6332.1 6389.5 6414.5 6415.1 6877.3 6879.6 6931.2 6949.7 6967.0 6249.8 6312.1 6339.5 6371.1 6381.6 5549.2 5607.7 5614.1 5618.4 5622.2 7147.0 7166.0 7240.6 7254.8 7275.3 6785.0 6836.9 6866.5 6876.9 6878.3 7079.3 7143.6 7158.7 7164.5 7173.0 6673.3 6749.2 6759.1 6825.6 6835.9 6870.7 6973.9 6983.7 6984.2 6984.9 6614.7 6639.2 6673.3 6713.0 6738.1 6495.9 6584.3 6588.1 6616.5 6699.4 6601.9 6661.7 6664.7 6664.8 6668.3 6674.7 6676.8 6682.3 6720.8 6770.3 6334.6 6411.6 6414.2 6460.2 6572.7 04:06:14 INFO - PID 22021 | 04:06:14 INFO - PID 22021 | [#1] ext.html Cycles:180 Average:43107.74 Median:3730.63 stddev:66738.03 (1788.9%) stddev-sans-first:66849.99 04:06:14 INFO - PID 22021 | Values: 897.0 899.5 925.0 952.0 956.0 43550.0 44592.3 44712.3 44738.6 44765.6 41796.8 41800.0 41805.2 42215.0 42257.8 7379.5 7479.1 7479.6 7482.0 7482.6 7322.5 7407.9 7409.1 7419.8 7436.6 8066.2 8302.1 8307.3 8307.8 8312.2 8216.5 8278.0 8284.0 8284.9 8290.5 7878.1 8137.2 8141.1 8141.5 8170.5 40804.4 41286.8 41290.4 41317.8 41330.0 3704.8 3756.5 3756.5 3757.6 3757.9 33454.5 33520.3 33544.2 33566.8 33692.8 5478.9 5501.2 5520.6 5524.4 5562.0 218589.0 219052.0 219260.0 220594.0 220699.0 51342.2 51565.7 51567.5 51748.2 51750.0 53964.7 54014.0 54039.7 54089.8 54152.0 15645.5 15893.3 15907.2 15953.5 15972.6 12714.1 12726.6 12744.5 12747.3 12790.5 15039.0 15263.4 15332.0 15369.0 15394.0 5985.9 6033.7 6058.8 6087.6 6316.7 1072.9 1080.2 1113.5 1117.4 1119.2 25564.4 25646.5 25651.9 25864.9 25953.7 1195.5 1205.0 1208.6 1214.6 1228.5 13538.2 13604.9 13667.1 13678.6 13761.0 293115.0 293438.0 293934.0 293958.0 294732.0 56476.4 60533.7 61296.7 61305.0 61322.4 1472.9 1498.2 1508.5 1518.2 1533.7 29222.9 29277.1 30005.4 30305.4 30382.7 40359.2 40537.6 40585.6 40601.8 40701.8 249086.0 249905.0 250236.0 250634.0 250672.0 34863.3 34975.0 35083.1 35089.1 35143.0 45098.8 45123.0 45164.8 45281.8 45484.5 34163.7 34251.8 34272.3 34553.7 34717.5 34073.8 34093.3 34370.0 34389.8 34720.5 39736.4 39784.8 40284.6 40335.0 40609.0 32837.8 33223.7 33293.5 33501.3 33810.5 25278.4 25426.7 25498.5 25681.2 25725.2 04:06:14 INFO - PID 22021 | 04:06:14 INFO - PID 22021 | [#2] jquery.html Cycles:180 Average:41445.93 Median:2275.24 stddev:109823.32 (4826.9%) stddev-sans-first:110088.58 04:06:14 INFO - PID 22021 | Values: 598.5 599.8 611.1 617.7 624.1 23291.9 23876.9 24055.4 24081.9 24085.9 24203.0 26331.7 26558.7 26587.2 26619.1 6436.7 6534.2 6566.9 6571.9 6580.9 6441.9 6481.1 6730.6 6739.6 6741.2 6716.8 6726.1 6727.6 6729.7 6730.4 6495.7 6672.0 6690.1 6717.9 6778.5 6333.8 6336.9 6339.7 6349.9 6356.4 30772.5 31160.4 31167.7 31192.2 31219.5 2257.5 2273.5 2277.0 2278.3 2281.9 20651.3 21039.9 21087.0 21110.9 21146.8 2569.9 2604.1 2653.7 2660.3 2667.9 133241.0 141373.0 148256.0 155674.0 156311.2 16045.4 16626.3 16648.9 16856.0 16878.2 25974.2 25999.5 26040.5 26042.6 26057.7 10455.7 10598.4 10600.9 10654.1 10716.6 9439.6 9459.1 9532.4 9595.4 9609.4 9891.4 10008.8 10032.2 10102.8 10141.8 2880.0 2922.7 2923.9 2925.5 3039.0 1029.2 1047.7 1056.7 1060.2 1061.4 21493.0 22019.3 22063.6 22239.7 22296.2 1097.6 1113.8 1118.9 1121.1 1128.9 11736.6 11908.8 11940.7 12044.5 12121.2 643615.0 656770.0 658792.0 664472.0 675303.0 133963.0 135631.0 135922.0 135972.0 140039.0 1201.8 1222.1 1225.7 1247.5 1252.7 18452.7 18802.6 18998.9 19077.9 19309.9 62558.7 62788.4 63306.4 63936.4 65417.7 76938.6 77931.6 78074.0 78103.0 78798.0 15431.6 15885.0 15921.2 15975.5 16000.3 24967.8 25153.9 25264.2 25447.0 25517.5 19914.2 20250.2 20341.4 20379.9 20380.6 16393.0 16906.2 17442.3 17635.8 17860.5 20200.1 20476.6 20498.8 20648.6 20788.0 15575.7 15580.2 15728.9 15735.0 15745.3 19762.8 20079.7 20103.6 20264.1 20293.7 04:06:14 INFO - PID 22021 | 04:06:14 INFO - PID 22021 | [#3] mootools.html Cycles:180 Average:10367.24 Median:2776.03 stddev:7664.07 (276.1%) stddev-sans-first:7655.73 04:06:14 INFO - PID 22021 | Values: 1366.3 1461.7 1468.7 1494.8 1501.3 16328.4 16867.0 16868.8 16871.2 16964.2 16564.0 16645.9 16710.7 16835.2 16868.6 2765.6 2775.7 2829.4 2829.7 2833.1 2741.8 2777.3 2787.3 2794.0 2808.1 2639.2 2736.6 2851.3 2852.1 2860.5 2786.2 2795.4 2798.9 2817.7 2823.8 2750.4 2760.4 2776.4 2779.0 2791.4 16281.8 16632.8 16673.8 16707.9 16773.4 7836.9 8118.3 8118.8 8129.1 8141.9 5319.6 5368.5 5478.5 5526.0 5651.0 2065.7 2072.5 2075.7 2088.5 2142.6 29738.9 29922.3 29970.0 30003.1 30003.8 6987.7 7027.2 7067.5 7157.7 7198.1 19728.9 19735.8 19756.0 19838.5 19838.7 8378.6 8379.4 8381.3 8383.8 8390.5 7640.9 7655.2 7739.2 7740.9 7744.1 5671.7 5813.0 5828.7 5873.6 5885.2 1560.9 1595.5 1634.1 1655.7 1680.6 952.2 996.4 997.3 1002.4 1006.5 9207.7 9377.0 9633.9 9643.9 9741.0 812.3 883.7 883.9 1081.7 1092.4 5930.5 5933.7 5956.8 6028.5 6066.1 23058.1 23111.8 23984.9 24088.4 25399.7 25326.0 25386.0 25409.9 25534.4 25727.0 11423.9 12370.5 12627.2 12661.1 12744.1 9550.6 9645.9 9734.8 9818.4 9906.0 20893.6 21066.2 21839.5 21884.0 22094.2 21670.6 21862.0 22002.0 22216.0 22218.9 12109.0 12343.6 12350.7 12351.6 12352.6 15428.5 15450.7 15646.1 15731.5 15776.4 12607.2 12634.2 12666.7 13030.8 13144.4 12408.9 12498.9 12504.2 12561.9 12818.0 9703.0 9743.7 9759.8 9917.4 9925.1 7893.5 7925.8 7926.1 8016.9 8096.9 8093.4 8129.8 8188.1 8191.0 8305.5 04:06:14 INFO - PID 22021 | 04:06:14 INFO - PID 22021 | [#4] prototype.html Cycles:180 Average:5580.90 Median:6199.27 stddev:1555.83 (25.1%) stddev-sans-first:1513.58 04:06:14 INFO - PID 22021 | Values: 544.8 547.5 571.4 577.9 587.2 6153.4 6243.8 6266.6 6320.9 6392.9 6106.0 6156.9 6160.4 6188.7 6209.8 5071.6 5106.3 5127.3 5141.4 5158.9 4885.9 4951.9 5076.7 5117.6 5165.8 4901.1 4912.2 4941.6 4975.3 5031.4 4906.6 4929.4 4940.8 5001.7 5032.9 4412.0 4484.1 4498.5 4544.2 4563.2 5974.4 6089.4 6096.8 6113.6 6153.6 5688.1 5713.6 5788.1 5788.9 5799.9 6504.7 6540.4 6580.5 6627.8 6632.9 4767.9 4998.8 5033.8 5043.0 5069.5 6630.4 6748.7 6875.1 6886.5 6900.3 5016.5 5042.3 5055.6 5107.7 5112.8 5075.2 5115.7 5150.2 5152.1 5167.1 5694.7 5695.3 5722.3 5730.3 5740.3 6553.4 6620.4 6620.9 6703.4 6718.0 1765.7 1791.9 1795.2 1827.5 1830.1 1225.9 1288.4 1293.2 1293.3 1297.8 6054.2 6215.3 6216.3 6237.2 6241.6 6454.9 6626.9 6698.3 6724.5 6799.0 6014.0 6057.8 6072.8 6117.7 6132.3 3486.5 3494.5 3495.2 3538.4 3563.3 6790.9 6831.3 6934.9 6988.7 7053.0 6775.6 6783.3 6811.7 6848.3 6921.2 6846.8 6903.9 6938.2 6961.9 7060.9 6688.3 6712.4 6714.6 6716.4 6756.6 6573.4 6578.6 6602.0 6668.9 6696.9 6865.9 6887.6 6902.9 6960.4 7021.3 5167.0 5169.4 5177.7 5200.3 5211.9 6396.6 6587.6 6705.2 6733.0 6818.9 6421.6 6477.2 6488.9 6512.4 6524.3 6406.4 6479.4 6484.4 6533.5 6570.4 6215.9 6327.2 6381.2 6463.5 6505.5 6497.9 6516.5 6596.5 6608.3 6627.9 6221.8 6288.6 6300.5 6360.8 6389.0 04:06:14 INFO - PID 22021 | 04:06:14 INFO - PID 22021 | [#5] yui.html Cycles:180 Average:27779.74 Median:18319.95 stddev:41153.93 (224.6%) stddev-sans-first:41220.24 04:06:14 INFO - PID 22021 | Values: 996.7 1011.3 1013.9 1015.9 1023.7 19154.9 19545.2 19564.8 19598.3 20140.9 18258.2 18301.9 18338.0 18363.8 18408.0 1000.8 1011.4 1014.8 1015.5 1015.7 1050.3 1082.1 1083.8 1085.2 1097.1 1138.2 1160.0 1160.2 1160.3 1160.6 1106.3 1141.5 1145.3 1148.3 1161.3 808.7 826.8 831.8 832.3 834.7 21091.6 21218.9 21238.5 21250.4 21273.8 19544.9 19728.3 19948.6 19964.8 20235.3 17429.6 17537.3 18122.5 18215.1 18237.6 3872.9 3901.1 3901.3 3903.6 3911.2 143330.3 147157.3 148112.5 148847.0 148959.0 15484.9 15496.9 15672.2 15687.5 15710.4 31042.7 32389.7 32588.7 32655.3 32696.3 8754.1 8889.6 8931.2 8997.7 9024.9 4314.2 4387.8 4401.9 4402.2 4431.5 6171.1 6277.3 6558.2 6729.8 6738.6 1217.5 1238.1 1259.5 1263.0 1263.7 757.9 772.3 781.9 788.4 794.2 12989.7 14008.3 14304.9 14315.4 14632.0 676.9 685.8 692.7 695.8 697.5 6554.2 6569.4 6603.0 6640.2 6662.7 144959.0 148405.0 149246.0 150732.0 154597.2 128586.0 129631.4 130628.0 133522.3 134949.0 110843.0 111516.4 113168.0 113538.0 116030.0 12332.3 12366.5 12379.4 12407.5 12592.6 55490.4 55640.2 55895.2 56213.7 57825.4 71426.6 71780.3 71909.6 73239.0 75996.6 17621.4 17761.4 17799.8 17937.0 17951.6 18929.1 19252.5 19413.1 19474.7 19555.9 18492.4 18561.2 18664.4 18789.2 18888.5 18403.4 18482.6 18542.4 18546.8 18620.1 17230.2 17267.6 17349.4 17527.1 17563.2 13285.3 13352.0 13355.8 13403.5 13472.2 13751.4 13904.2 13969.2 14121.2 14224.9 04:06:14 INFO - PID 22021 | -------- Summary: end -------- 04:06:14 INFO - PID 22021 | 04:06:14 INFO - TEST-INFO | 22021: exit 0 04:06:14 INFO - TEST-OK | dromaeo_css | took 1107348ms 04:06:14 INFO - TEST-START | kraken 04:06:14 INFO - Initialising browser for kraken test... 04:06:14 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36353/getInfo.html -profile /tmp/tmpLqDyzv/profile 04:06:14 INFO - TEST-INFO | started process 22405 (/builds/slave/test/build/application/firefox/firefox http://localhost:36353/getInfo.html) 04:06:20 INFO - TEST-INFO | 22405: exit 0 04:06:20 INFO - Browser initialized. 04:06:20 INFO - Running cycle 1/1 for kraken test... 04:06:20 INFO - Using env: {'DISPLAY': ':0', 04:06:20 INFO - 'HOME': '/home/cltbld', 04:06:20 INFO - 'JSGC_DISABLE_POISONING': '1', 04:06:20 INFO - 'LANG': 'en_US.UTF-8', 04:06:20 INFO - 'LANGUAGE': 'en_US:en', 04:06:20 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:06:20 INFO - 'LOGNAME': 'cltbld', 04:06:20 INFO - 'MAIL': '/var/mail/cltbld', 04:06:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:06:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:06:20 INFO - 'MOZ_CRASHREPORTER': '1', 04:06:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:06:20 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:06:20 INFO - 'MOZ_NO_REMOTE': '1', 04:06:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:06:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:06:20 INFO - 'NO_EM_RESTART': '1', 04:06:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:06:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:06:20 INFO - 'PWD': '/builds/slave/test', 04:06:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:06:20 INFO - 'RUST_BACKTRACE': 'full', 04:06:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:06:20 INFO - 'SHELL': '/bin/bash', 04:06:20 INFO - 'SHLVL': '1', 04:06:20 INFO - 'TERM': 'linux', 04:06:20 INFO - 'TMOUT': '86400', 04:06:20 INFO - 'USER': 'cltbld', 04:06:20 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332', 04:06:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:06:20 INFO - '_': '/tools/buildbot/bin/python'} 04:06:20 INFO - TEST-INFO | started process 22610 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLqDyzv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:06:21 INFO - PID 22610 | 04:06:21 INFO - PID 22610 | (/builds/slave/test/build/application/firefox/firefox:22662): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:06:21 INFO - PID 22610 | 04:06:31 INFO - PID 22610 | RSS: Main: 164364288 04:06:31 INFO - PID 22610 | 04:06:34 INFO - PID 22610 | Cycle 1(1): loaded http://localhost:36353/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36353/tests/kraken/driver.html?testName=audio-beat-detection) 04:06:35 INFO - PID 22610 | RSS: Main: 169799680 04:06:35 INFO - PID 22610 | 04:06:37 INFO - PID 22610 | Cycle 1(1): loaded http://localhost:36353/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36353/tests/kraken/driver.html?testName=audio-dft) 04:06:38 INFO - PID 22610 | RSS: Main: 170303488 04:06:38 INFO - PID 22610 | 04:06:41 INFO - PID 22610 | Cycle 1(1): loaded http://localhost:36353/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36353/tests/kraken/driver.html?testName=audio-fft) 04:06:41 INFO - PID 22610 | RSS: Main: 165466112 04:06:41 INFO - PID 22610 | 04:06:43 INFO - PID 22610 | Cycle 1(1): loaded http://localhost:36353/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36353/tests/kraken/driver.html?testName=audio-oscillator) 04:06:44 INFO - PID 22610 | RSS: Main: 166256640 04:06:44 INFO - PID 22610 | 04:06:46 INFO - PID 22610 | Cycle 1(1): loaded http://localhost:36353/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36353/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:06:47 INFO - PID 22610 | RSS: Main: 166428672 04:06:47 INFO - PID 22610 | 04:06:50 INFO - PID 22610 | Cycle 1(1): loaded http://localhost:36353/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36353/tests/kraken/driver.html?testName=imaging-darkroom) 04:06:51 INFO - PID 22610 | RSS: Main: 170041344 04:06:51 INFO - PID 22610 | 04:06:54 INFO - PID 22610 | Cycle 1(1): loaded http://localhost:36353/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36353/tests/kraken/driver.html?testName=imaging-desaturate) 04:06:55 INFO - PID 22610 | RSS: Main: 169041920 04:06:55 INFO - PID 22610 | 04:06:58 INFO - PID 22610 | Cycle 1(1): loaded http://localhost:36353/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36353/tests/kraken/driver.html?testName=json-parse-financial) 04:06:58 INFO - PID 22610 | RSS: Main: 169439232 04:06:58 INFO - PID 22610 | 04:07:00 INFO - PID 22610 | Cycle 1(1): loaded http://localhost:36353/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36353/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:07:00 INFO - PID 22610 | RSS: Main: 169373696 04:07:00 INFO - PID 22610 | 04:07:02 INFO - PID 22610 | Cycle 1(1): loaded http://localhost:36353/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36353/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:07:03 INFO - PID 22610 | RSS: Main: 169635840 04:07:03 INFO - PID 22610 | 04:07:05 INFO - PID 22610 | Cycle 1(1): loaded http://localhost:36353/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36353/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:07:05 INFO - PID 22610 | RSS: Main: 166465536 04:07:05 INFO - PID 22610 | 04:07:08 INFO - PID 22610 | Cycle 1(1): loaded http://localhost:36353/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36353/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:07:08 INFO - PID 22610 | RSS: Main: 166281216 04:07:08 INFO - PID 22610 | 04:07:11 INFO - PID 22610 | Cycle 1(1): loaded http://localhost:36353/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36353/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:07:12 INFO - PID 22610 | RSS: Main: 166887424 04:07:12 INFO - PID 22610 | 04:07:14 INFO - PID 22610 | Cycle 1(1): loaded http://localhost:36353/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36353/tests/kraken/driver.html?testName=ai-astar) 04:07:14 INFO - PID 22610 | RSS: Main: 168497152 04:07:14 INFO - PID 22610 | 04:07:14 INFO - PID 22610 | __start_tp_report 04:07:14 INFO - PID 22610 | _x_x_mozilla_page_load 04:07:14 INFO - PID 22610 | _x_x_mozilla_page_load_details 04:07:14 INFO - PID 22610 | |i|pagename|runs| 04:07:14 INFO - PID 22610 | |0;ai-astar;105;105;106;105;105;107;107;108;106;106 04:07:14 INFO - PID 22610 | |1;audio-beat-detection;112;116;115;113;113;133;111;115;113;112 04:07:14 INFO - PID 22610 | |2;audio-dft;161;160;162;161;165;161;161;161;162;170 04:07:14 INFO - PID 22610 | |3;audio-fft;76;74;73;73;73;73;74;73;74;75 04:07:14 INFO - PID 22610 | |4;audio-oscillator;81;78;78;78;77;78;78;79;80;78 04:07:14 INFO - PID 22610 | |5;imaging-gaussian-blur;114;116;109;114;114;114;113;114;115;113 04:07:14 INFO - PID 22610 | |6;imaging-darkroom;150;151;149;149;149;150;148;149;151;150 04:07:14 INFO - PID 22610 | |7;imaging-desaturate;93;94;94;94;94;94;93;93;94;94 04:07:14 INFO - PID 22610 | |8;json-parse-financial;44;45;44;44;45;45;45;43;45;44 04:07:14 INFO - PID 22610 | |9;json-stringify-tinderbox;37;38;37;36;37;38;36;37;37;37 04:07:14 INFO - PID 22610 | |10;stanford-crypto-aes;75;75;71;72;72;72;72;72;72;72 04:07:14 INFO - PID 22610 | |11;stanford-crypto-ccm;112;111;116;121;111;107;111;117;116;107 04:07:14 INFO - PID 22610 | |12;stanford-crypto-pbkdf2;164;161;156;157;154;166;155;162;152;157 04:07:14 INFO - PID 22610 | |13;stanford-crypto-sha256-iterative;63;60;59;59;65;61;59;61;58;60 04:07:14 INFO - PID 22610 | __end_tp_report 04:07:14 INFO - PID 22610 | __start_cc_report 04:07:14 INFO - PID 22610 | _x_x_mozilla_cycle_collect,914 04:07:14 INFO - PID 22610 | __end_cc_report 04:07:14 INFO - PID 22610 | __startTimestamp1505128034267__endTimestamp 04:07:14 INFO - PID 22610 | 04:07:14 INFO - PID 22610 | ------- Summary: start ------- 04:07:14 INFO - PID 22610 | Number of tests: 14 04:07:14 INFO - PID 22610 | 04:07:14 INFO - PID 22610 | [#0] ai-astar Cycles:10 Average:106.00 Median:106.00 stddev:1.05 (1.0%) stddev-sans-first:1.05 04:07:14 INFO - PID 22610 | Values: 105.0 105.0 106.0 105.0 105.0 107.0 107.0 108.0 106.0 106.0 04:07:14 INFO - PID 22610 | 04:07:14 INFO - PID 22610 | [#1] audio-beat-detection Cycles:10 Average:115.30 Median:114.00 stddev:6.41 (5.6%) stddev-sans-first:6.69 04:07:14 INFO - PID 22610 | Values: 112.0 116.0 115.0 113.0 113.0 133.0 111.0 115.0 113.0 112.0 04:07:14 INFO - PID 22610 | 04:07:14 INFO - PID 22610 | [#2] audio-dft Cycles:10 Average:162.40 Median:161.50 stddev:2.99 (1.9%) stddev-sans-first:3.13 04:07:14 INFO - PID 22610 | Values: 161.0 160.0 162.0 161.0 165.0 161.0 161.0 161.0 162.0 170.0 04:07:14 INFO - PID 22610 | 04:07:14 INFO - PID 22610 | [#3] audio-fft Cycles:10 Average:73.80 Median:74.00 stddev:1.03 (1.4%) stddev-sans-first:0.73 04:07:14 INFO - PID 22610 | Values: 76.0 74.0 73.0 73.0 73.0 73.0 74.0 73.0 74.0 75.0 04:07:14 INFO - PID 22610 | 04:07:14 INFO - PID 22610 | [#4] audio-oscillator Cycles:10 Average:78.50 Median:78.00 stddev:1.18 (1.5%) stddev-sans-first:0.83 04:07:14 INFO - PID 22610 | Values: 81.0 78.0 78.0 78.0 77.0 78.0 78.0 79.0 80.0 78.0 04:07:14 INFO - PID 22610 | 04:07:14 INFO - PID 22610 | [#5] imaging-gaussian-blur Cycles:10 Average:113.60 Median:114.00 stddev:1.84 (1.6%) stddev-sans-first:1.94 04:07:14 INFO - PID 22610 | Values: 114.0 116.0 109.0 114.0 114.0 114.0 113.0 114.0 115.0 113.0 04:07:14 INFO - PID 22610 | 04:07:14 INFO - PID 22610 | [#6] imaging-darkroom Cycles:10 Average:149.60 Median:150.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 04:07:14 INFO - PID 22610 | Values: 150.0 151.0 149.0 149.0 149.0 150.0 148.0 149.0 151.0 150.0 04:07:14 INFO - PID 22610 | 04:07:14 INFO - PID 22610 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 04:07:14 INFO - PID 22610 | Values: 93.0 94.0 94.0 94.0 94.0 94.0 93.0 93.0 94.0 94.0 04:07:14 INFO - PID 22610 | 04:07:14 INFO - PID 22610 | [#8] json-parse-financial Cycles:10 Average:44.40 Median:45.00 stddev:0.70 (1.6%) stddev-sans-first:0.73 04:07:14 INFO - PID 22610 | Values: 44.0 45.0 44.0 44.0 45.0 45.0 45.0 43.0 45.0 44.0 04:07:14 INFO - PID 22610 | 04:07:14 INFO - PID 22610 | [#9] json-stringify-tinderbox Cycles:10 Average:37.00 Median:37.00 stddev:0.67 (1.8%) stddev-sans-first:0.71 04:07:14 INFO - PID 22610 | Values: 37.0 38.0 37.0 36.0 37.0 38.0 36.0 37.0 37.0 37.0 04:07:14 INFO - PID 22610 | 04:07:14 INFO - PID 22610 | [#10] stanford-crypto-aes Cycles:10 Average:72.50 Median:72.00 stddev:1.35 (1.9%) stddev-sans-first:1.09 04:07:14 INFO - PID 22610 | Values: 75.0 75.0 71.0 72.0 72.0 72.0 72.0 72.0 72.0 72.0 04:07:14 INFO - PID 22610 | 04:07:14 INFO - PID 22610 | [#11] stanford-crypto-ccm Cycles:10 Average:112.90 Median:114.00 stddev:4.51 (4.0%) stddev-sans-first:4.77 04:07:14 INFO - PID 22610 | Values: 112.0 111.0 116.0 121.0 111.0 107.0 111.0 117.0 116.0 107.0 04:07:14 INFO - PID 22610 | 04:07:14 INFO - PID 22610 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.40 Median:159.00 stddev:4.60 (2.9%) stddev-sans-first:4.41 04:07:14 INFO - PID 22610 | Values: 164.0 161.0 156.0 157.0 154.0 166.0 155.0 162.0 152.0 157.0 04:07:14 INFO - PID 22610 | 04:07:14 INFO - PID 22610 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.50 Median:60.50 stddev:2.12 (3.5%) stddev-sans-first:2.05 04:07:14 INFO - PID 22610 | Values: 63.0 60.0 59.0 59.0 65.0 61.0 59.0 61.0 58.0 60.0 04:07:14 INFO - PID 22610 | -------- Summary: end -------- 04:07:14 INFO - PID 22610 | 04:07:14 INFO - PID 22610 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:07:14 INFO - TEST-INFO | 22610: exit 0 04:07:14 INFO - TEST-OK | kraken | took 60195ms 04:07:14 INFO - SUITE-END | took 1167s 04:07:15 INFO - Completed test suite (00:19:27) 04:07:15 INFO - javascript benchmark 04:07:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9050.575569315784, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3890.8327501749477, 3893.3770596704517, 3897.415387691844, 3912.3935789952548, 3922.666573341264, 6205.658688772116, 6257.155284036654, 6322.968385158074, 6331.715072821723, 6411.455026322762, 6321.0, 6324.083007963843, 6331.556791024618, 6411.711472983727, 6435.6782160892, 5576.275084239043, 5681.375048744635, 5682.744276507547, 5697.116946873241, 5699.316082070158, 265.8729865660288, 5380.91928621071, 5643.4074422185695, 5672.744726487297, 5679.829605111832, 5574.191742197399, 5596.748146333425, 5657.0, 5683.374828768832, 5702.543796496312, 5606.6636001839615, 5635.042042852725, 5698.287714035746, 5704.2299289595785, 5711.571632127607, 5627.662340259557, 5629.4652008059165, 5694.5159661428415, 5697.116946873241, 5698.544116470714, 6069.482629342664, 6234.688265586702, 6296.811095667092, 6322.430981211667, 6335.271443783978, 6743.426808721265, 6815.114035175396, 6825.453963682893, 6831.351021652985, 6834.350736680008, 6885.277045910208, 6916.273791251947, 6995.8950615740805, 6996.790096297119, 7002.299770023008, 6393.9360606394275, 6424.4860411167465, 6461.1600491936215, 6475.5467117301805, 6488.091667166554, 7081.185663648644, 7184.425245980309, 7190.7483238086415, 7209.026781384446, 7211.2428195038765, 6813.284605116491, 6815.2843951385385, 6885.552439091443, 6889.827754306182, 6895.0, 6820.181578210547, 6897.448204143657, 6899.48253880961, 6942.548734332353, 6984.546004509691, 6829.078074459983, 6843.520953533304, 6854.862902741917, 6858.5199036068, 6876.553024053421, 6702.229243636946, 6784.287649796799, 6877.518573699893, 6882.827929301808, 6886.380225779704, 4967.652264341461, 4969.726665033458, 5027.924581131286, 5036.496350364934, 5038.84883453497, 4521.59305662492, 4568.862934111982, 4605.378273933044, 4613.907721845544, 4655.208614535537, 6327.050942358683, 6332.1135041094285, 6389.488840892717, 6414.518911081687, 6415.133956915756, 6877.346652068046, 6879.621620810904, 6931.168259808755, 6949.652517374111, 6967.0, 6249.812505624838, 6312.116303717483, 6339.461145802658, 6371.108044873721, 6381.585196962183, 5549.1676248562235, 5607.691576963224, 5614.129809879475, 5618.353889302701, 5622.156676498558, 7146.964265178744, 7166.0, 7240.637968101679, 7254.782356529313, 7275.345218930322, 6784.966075169593, 6836.863262734817, 6866.519343645897, 6876.931230687629, 6878.312168783082, 7079.2566780487305, 7143.607101609254, 7158.749443769443, 7164.498485105993, 7172.964135179291, 6673.265940746417, 6749.223839258548, 6759.053732477359, 6825.590464572141, 6835.897461537981, 6870.690818913261, 6973.860522789414, 6983.650817459208, 6984.196817366068, 6984.930150698631, 6614.735410583523, 6639.2364878037715, 6673.332666733288, 6713.026611141259, 6738.124043874265, 6495.9025614614875, 6584.275729669636, 6588.143541339595, 6616.470682345497, 6699.43054840344, 6601.9009714853355, 6661.666916654245, 6664.7000884959325, 6664.766733164316, 6668.266490686117, 6674.733010679518, 6676.83307917306, 6682.298358672464, 6720.798376048726, 6770.255271919987, 6334.588251763621, 6411.647359395091, 6414.198225221848, 6460.224773027079, 6572.704228309642], "value": 6233.026977303382, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [896.9955150224253, 899.5325380089366, 925.0379605210582, 952.0099096939183, 956.0105291023792, 43550.0, 44592.3311150333, 44712.3293150603, 44738.55261447385, 44765.55234447654, 41796.791016044954, 41800.0, 41805.16389672205, 42215.0, 42257.78871105648, 7379.52033117847, 7479.065116860393, 7479.62601869907, 7482.027336446282, 7482.625868706543, 7322.48742588019, 7407.851842963138, 7409.14794798597, 7419.777406677808, 7436.590987495686, 8066.153053929341, 8302.086770455246, 8307.252347288773, 8307.792305192359, 8312.1687831217, 8216.465929714579, 8277.95861020691, 8284.047334556542, 8284.917150828474, 8290.544020078893, 7878.054633444023, 8137.226963438464, 8141.145179756099, 8141.470804397754, 8170.468919520212, 40804.38793418101, 41286.79356603198, 41290.38064429036, 41317.79341103305, 41330.0, 3704.7962362070075, 3756.455313979485, 3756.549214094299, 3757.605451427588, 3757.8684746034032, 33454.498182527284, 33520.32959340824, 33544.16139596505, 33566.83216583926, 33692.83153584216, 5478.945210547883, 5501.229827824108, 5520.613557051008, 5524.392316845144, 5561.972190139064, 218589.0, 219052.0, 219260.0, 220594.0, 220698.9999999984, 51342.22986655203, 51565.742171288905, 51567.48432515627, 51748.22377664338, 51750.0, 53964.730176348865, 54014.0, 54039.729801351525, 54089.8364065433, 54152.0, 15645.530634080997, 15893.284802183929, 15907.204639767966, 15953.521394358188, 15972.600684982968, 12714.110012299236, 12726.554570589984, 12744.490220391266, 12747.298898560482, 12790.488380464678, 15039.0, 15263.389464421522, 15332.0, 15369.0, 15394.0, 5985.85216713626, 6033.698315084228, 6058.818235452944, 6087.634741915499, 6316.747330106836, 1072.8658917635296, 1080.2276372393776, 1113.5267511307825, 1117.3519358771894, 1119.2445099557765, 25564.360890977874, 25646.487070258863, 25651.871740641178, 25864.8706756465, 25953.74046259551, 1195.468016732563, 1204.987950120505, 1208.6434501821984, 1214.623466725318, 1228.5147366790068, 13538.187708737507, 13604.863951360558, 13667.111637743516, 13678.589642310746, 13761.0, 293115.0, 293438.0, 293934.0, 293958.0, 294732.0, 56476.43523564712, 60533.69733151306, 61296.693516532134, 61305.0, 61322.386776131665, 1472.9410823566936, 1498.1834899979538, 1508.4644951042442, 1518.2484670088377, 1533.7392643250673, 29222.853885730434, 29277.121686349445, 30005.399892002475, 30305.393892122476, 30382.696173037988, 40359.192816142924, 40537.59462405338, 40585.59414405936, 40601.79699101485, 40701.796491017354, 249086.0, 249905.0, 250236.0, 250634.0, 250672.0, 34863.30273394569, 34975.0, 35083.122921927155, 35089.1227719309, 35143.0, 45098.774506128575, 45123.0, 45164.77417612891, 45281.77359113183, 45484.545154549356, 34163.65836341605, 34251.82874085614, 34272.31455370829, 34553.654463455045, 34717.47923781196, 34073.82963085169, 34093.31813363769, 34370.0, 34389.828050859585, 34720.479192812636, 39736.403953940135, 39784.80107599444, 40284.59715402926, 40335.0, 40609.0, 32837.83581082079, 33223.66776332206, 33293.50059749057, 33501.32997340088, 33810.49284260689, 25278.368040798392, 25426.74573254318, 25498.457452650375, 25681.22956311314, 25725.228243152735], "value": 18507.421869565955, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.5191381722758, 599.802065318445, 611.126089691741, 617.6973283091281, 624.0825985800873, 23291.883540582294, 23876.88061559694, 24055.39861503461, 24081.879590602046, 24085.879570602166, 24203.03187872483, 26331.736682633167, 26558.734412655867, 26587.202383928463, 26619.068332608364, 6436.678166091689, 6534.150560427138, 6566.901496477557, 6571.901421478682, 6580.901286480706, 6441.8711625767455, 6481.09264702942, 6730.5625134366355, 6739.561928474658, 6741.224759152711, 6716.764913228038, 6726.058351830723, 6727.596344219338, 6729.7308107675635, 6730.394264516192, 6495.740170393178, 6672.0, 6690.063391125246, 6717.8992315115065, 6778.457723382117, 6333.809985700436, 6336.904946425807, 6339.651319177444, 6349.904751428732, 6356.364363563653, 30772.53841192384, 31160.37679246425, 31167.68832311693, 31192.220194495094, 31219.531707024413, 2257.548490301946, 2273.4771002669477, 2277.0, 2278.2823410625765, 2281.931542053741, 20651.27720529774, 21039.8948005259, 21087.0, 21110.944452777454, 21146.788532114635, 2569.9486010279875, 2604.075553178613, 2653.6815582130266, 2660.255128564005, 2667.9199624011117, 133241.0, 141373.0, 148256.0, 155674.0, 156311.21844390818, 16045.438409655839, 16626.33494660224, 16648.916755416387, 16856.0, 16878.240479178465, 25974.22077337683, 25999.480010399686, 26040.479190416085, 26042.609360859602, 26057.73942260591, 10455.68632941013, 10598.417087060132, 10600.893991060146, 10654.094401975764, 10716.571337146426, 9439.622415103318, 9459.10138536838, 9532.428054316762, 9595.42427454341, 9609.37539059973, 9891.406515609087, 10008.799824003625, 10032.197424206046, 10102.797944041078, 10141.797164056678, 2879.985600071986, 2922.678505364408, 2923.9415211696073, 2925.546540286259, 3038.9848050759606, 1029.1509504658686, 1047.701405099543, 1056.7199692081604, 1060.204846365226, 1061.4162210784193, 21493.0, 22019.339419817432, 22063.558728825654, 22239.666405003936, 22296.21963231279, 1097.6048622495896, 1113.8050841102774, 1118.9384583847805, 1121.0527104596604, 1128.90968722502, 11736.647900562997, 11908.761824763456, 11940.701482463008, 12044.51821927113, 12121.212121212093, 643615.0, 656770.0, 658792.0, 664472.0, 675303.0, 133963.0, 135631.0, 135922.0, 135972.0, 140039.0, 1201.8077107662732, 1222.0529089955178, 1225.681322856046, 1247.457356050121, 1252.6868282929267, 18452.723209152144, 18802.6239475207, 18998.905005475437, 19077.90461047686, 19309.937953412697, 62558.6872065655, 62788.37211628008, 63306.36693633189, 63936.36063639304, 65417.672911635134, 76938.61530692535, 77931.61034194793, 78074.0, 78103.0, 78798.0, 15431.61420964485, 15885.046897186206, 15921.203939802732, 15975.520734377988, 16000.279987400361, 24967.750322496544, 25153.87423062873, 25264.23150379456, 25447.0, 25517.489650207262, 19914.20343186314, 20250.189992400137, 20341.389758307276, 20379.898100509403, 20380.59238815245, 16393.0, 16906.239219234918, 17442.302307907543, 17635.82364176393, 17860.464186074438, 20200.090995905513, 20476.590468190254, 20498.79501204969, 20648.58702825965, 20788.0, 15575.688486230438, 15580.220988950281, 15728.92135539315, 15735.0, 15745.291461884013, 19762.802371976097, 20079.69880451765, 20103.59792804165, 20264.08811603452, 20293.695594565797], "value": 12918.575743779953, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.3168415792104, 1461.6930444606633, 1468.6548661064655, 1494.7608382658773, 1501.2643804535774, 16328.428505002312, 16867.0, 16868.831311686896, 16871.240794164292, 16964.236609352578, 16564.0, 16645.916770416163, 16710.749338759928, 16835.242414091394, 16868.578285542866, 2765.5575107982727, 2775.680796708374, 2829.4058247767994, 2829.745322920937, 2833.135893552466, 2741.821781584191, 2777.347323379004, 2787.3310405502734, 2793.9580906286424, 2808.087371604237, 2639.155470249521, 2736.58951157327, 2851.3441908361087, 2852.101587999784, 2860.4994126027864, 2786.192004318745, 2795.35706787439, 2798.916032519028, 2817.7323154300307, 2823.759980401668, 2750.436160587095, 2760.4341110072387, 2776.3753155540044, 2779.0, 2791.3579876628487, 16281.755773663404, 16632.750508742378, 16673.83326166735, 16707.916460417742, 16773.412930547493, 7836.882446763303, 8118.309943654737, 8118.837623247561, 8129.065157506903, 8141.877871831927, 5319.627626066177, 5368.4631536846, 5478.479544443311, 5525.972370138164, 5651.0, 2065.659166237569, 2072.4818795301176, 2075.6886467029917, 2088.4778805298674, 2142.5607750411127, 29738.851305743334, 29922.25194370158, 29970.0, 30003.099907002827, 30003.849980749957, 6987.7204911802955, 7027.15674119109, 7067.469939754539, 7157.749478768218, 7198.100237470317, 19728.90135549313, 19735.802641973398, 19756.0, 19838.50403739918, 19838.70241946372, 8378.622961966605, 8379.413441059067, 8381.287590554872, 8383.832323353621, 8390.454620449651, 7640.923590764132, 7655.234476552411, 7739.1873853245725, 7740.8838867417035, 7744.148143704188, 5671.688057156896, 5813.04084825999, 5828.6502809831545, 5873.559483038789, 5885.234919460443, 1560.8985415947927, 1595.5133684226423, 1634.0685809088943, 1655.7350823868123, 1680.638662687528, 952.1573407534244, 996.4469719305855, 997.3168379659957, 1002.4185972136289, 1006.4766321512918, 9207.723768286962, 9376.95311523438, 9633.9036609633, 9643.903560964442, 9741.025897410202, 812.2567850416921, 883.7127933421664, 883.9204471597587, 1081.6538707613488, 1092.3882625729616, 5930.495907847795, 5933.703314834241, 5956.821295361146, 6028.457438830438, 6066.1204125402455, 23058.077676893074, 23111.7688823113, 23984.88007559951, 24088.397790055387, 25399.746002539738, 25326.0, 25386.0, 25409.872950635498, 25534.361640958752, 25727.0, 11423.942880285545, 12370.505179792888, 12627.179233349803, 12661.050421218446, 12744.10791244604, 9550.55619294652, 9645.855312170183, 9734.805303894023, 9818.410895346016, 9906.0, 20893.58212835765, 21066.157353706294, 21839.454013649785, 21883.999999999363, 22094.22670206535, 21670.566588668455, 21862.032417081904, 22002.0, 22216.0, 22218.888905556014, 12109.0, 12343.567975120828, 12350.691232719253, 12351.629451116121, 12352.62942111738, 15428.537143885702, 15450.690986179989, 15646.061236325857, 15731.528054158394, 15776.368945242062, 12607.243565386107, 12634.178778379375, 12666.683332916382, 13030.804537931748, 13144.408501617641, 12408.937955310166, 12498.937505312415, 12504.249745015328, 12561.87438125607, 12818.0, 9703.0, 9743.707688769347, 9759.804803904024, 9917.404955702681, 9925.1067403934, 7893.526388416483, 7925.841483170419, 7926.128125906031, 8016.9198308016175, 8096.919030809617, 8093.35253179756, 8129.75610731679, 8188.058373287148, 8190.959045204736, 8305.46014509055], "value": 7263.978855379688, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.8419958212118, 547.5181839980817, 571.4028839862343, 577.9104238842975, 587.2014060877207, 6153.35389784073, 6243.812685619428, 6266.62400255985, 6320.914098347545, 6392.872142557146, 6106.0, 6156.938430615693, 6160.383961603838, 6188.690565471731, 6209.844753881155, 5071.644984851062, 5106.336176297076, 5127.256547800566, 5141.357330333708, 5158.9484105158845, 4885.949520853016, 4951.876203094916, 5076.746162691869, 5117.616178786588, 5165.767540460688, 4901.142300097486, 4912.164931961575, 4941.579965702919, 4975.27858460523, 5031.396232452111, 4906.607471402279, 4929.383827021622, 4940.752962351868, 5001.7499125043605, 5032.892763592004, 4411.977940110311, 4484.125595508877, 4498.537975158054, 4544.182047231497, 4563.224251877189, 5974.402559744034, 6089.421504957022, 6096.7561297548045, 6113.602615830001, 6153.569250152491, 5688.094488346409, 5713.628614140068, 5788.131780232957, 5788.88422231553, 5799.855003624901, 6504.699060187982, 6540.37866402691, 6580.473562115068, 6627.801165965029, 6632.900506492407, 4767.928481072787, 4998.775055122528, 5033.7734801934, 5043.0, 5069.467705890866, 6630.403263706289, 6748.662566871637, 6875.140607424072, 6886.51794374394, 6900.3444672756, 5016.498350164954, 5042.3444952156715, 5055.573324804114, 5107.668001579886, 5112.769925353368, 5075.187969924795, 5115.667481613683, 5150.227465880073, 5152.098382783073, 5167.1474206755465, 5694.743736531866, 5695.259616249944, 5722.313322401338, 5730.312362516524, 5740.339860915965, 6553.442957348583, 6620.3710647488415, 6620.933790662128, 6703.363180497845, 6717.9664101680155, 1765.7174852023616, 1791.9014454205144, 1795.231696778672, 1827.470033690215, 1830.1398342778873, 1225.8896699297106, 1288.3880156925572, 1293.1982171054008, 1293.2822283632486, 1297.7923532234797, 6054.152418661391, 6215.34738852423, 6216.2540495140875, 6237.189165408558, 6241.563090583615, 6454.870902581922, 6626.933730662631, 6698.33016698336, 6724.462043036546, 6798.966005169942, 6014.0, 6057.7879774207695, 6072.787452439144, 6117.694115294217, 6132.264128304543, 3486.511888335635, 3494.5282386877952, 3495.178633021238, 3538.433850583894, 3563.2517171394297, 6790.898136527956, 6831.28271531482, 6934.895976560356, 6988.6855091521, 7052.9647351762915, 6775.593464392152, 6783.320588367567, 6811.693473793691, 6848.315168483112, 6921.169459664772, 6846.794596162223, 6903.861922761617, 6938.167419909542, 6961.860762784817, 7060.916140378919, 6688.331166883273, 6712.362325579063, 6714.597124172566, 6716.3955244028275, 6756.560823546453, 6573.40839324444, 6578.638174900427, 6601.966990165019, 6668.932291146127, 6696.899546506904, 6865.931340686729, 6887.552309099892, 6902.8619427610165, 6960.39910820504, 7021.262767409348, 5167.0, 5169.405518365436, 5177.663451875616, 5200.349956255468, 5211.869703257297, 6396.584222025554, 6587.571807832475, 6705.228898676714, 6733.0, 6818.8636227276165, 6421.614703117828, 6477.222733272038, 6488.870222595616, 6512.413882750386, 6524.347565243438, 6406.391392817675, 6479.41685248311, 6484.351564843667, 6533.477321814338, 6570.408663220333, 6215.906761398673, 6327.240731112296, 6381.170447841937, 6463.515236357198, 6505.544611877123, 6497.935020649733, 6516.543841931019, 6596.538242323183, 6608.339166083353, 6627.933720662924, 6221.844453888508, 6288.622682638873, 6300.487882908161, 6360.840978975378, 6388.955534229188], "value": 5135.588448768402, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [996.7159359582519, 1011.2516737614168, 1013.9036791504805, 1015.8984101589838, 1023.708243150702, 19154.90422547887, 19545.21819127235, 19564.804351956493, 19598.31405900794, 20140.899295503503, 18258.17838197281, 18301.90849045756, 18338.0, 18363.816361836376, 18408.0, 1000.8498725191225, 1011.3729487717625, 1014.8477728340735, 1015.5125539740928, 1015.7155996321005, 1050.3067975136412, 1082.123479981214, 1083.756154865157, 1085.1752667972357, 1097.067492631261, 1138.2032577195996, 1160.025578514048, 1160.1530882455813, 1160.257435241446, 1160.5995931403652, 1106.286445242814, 1141.5091510650418, 1145.34715212329, 1148.299537282253, 1161.3206274329527, 808.6684459371688, 826.8098337382436, 831.8003679117029, 832.309183377795, 834.7245409015011, 21091.578168436547, 21218.893905530527, 21238.469038274012, 21250.362489125197, 21273.787262127335, 19544.902275488676, 19728.309509167255, 19948.601027979505, 19964.80035199644, 20235.291764788162, 17429.564260893454, 17537.298508059663, 18122.546936326566, 18215.08924553767, 18237.63524729511, 3872.941905871386, 3901.1222474943193, 3901.2977664020464, 3903.609639036102, 3911.159100793343, 143330.28334858365, 147157.26421367933, 148112.51887481095, 148847.0, 148959.0, 15484.92257538705, 15496.92251538735, 15672.216389180496, 15687.450939217073, 15710.371585136696, 31042.68957310398, 32389.676103238668, 32588.67411325904, 32655.346893062007, 32696.346073078406, 8754.124587541195, 8889.599968001456, 8931.19619234259, 8997.730068097968, 9024.8646270306, 4314.22343978087, 4387.824487020547, 4401.867943961686, 4402.163588918095, 4431.468223813163, 6171.13604095427, 6277.340879207709, 6558.245801732766, 6729.831754206086, 6738.561993470506, 1217.4582310871754, 1238.1147479552164, 1259.483611719209, 1262.9936850315873, 1263.7472505498938, 757.9052618422697, 772.3319328780565, 781.9452638315263, 788.3929374381694, 794.1859593916282, 12989.740205195843, 14008.299585020708, 14304.928475357556, 14315.355808988621, 14632.048916820373, 676.9086173366533, 685.7908337956871, 692.6848284030754, 695.8295217671703, 697.5152269172876, 6554.180727409074, 6569.37590928861, 6602.966985165043, 6640.236372817188, 6662.6668666566475, 144959.0, 148405.0, 149246.0, 150732.0, 154597.22701386645, 128586.0, 129631.35184324018, 130628.0, 133522.33238833744, 134949.0, 110843.0, 111516.44241778902, 113168.0, 113538.0, 116030.0, 12332.321722305, 12366.505339786308, 12379.381030948596, 12407.473034285327, 12592.559260425842, 55490.44509554853, 55640.16539751988, 55895.16157257726, 56213.718931405085, 57825.421745783686, 71426.64286678533, 71780.28219717945, 71909.6404517974, 73239.0, 75996.62001689956, 17621.383251586132, 17761.401473867412, 17799.822001780336, 17937.0, 17951.640967180843, 18929.148188331284, 19252.518687032938, 19413.1264093119, 19474.707879382106, 19555.902220488806, 18492.35276765307, 18561.16474758666, 18664.440066797473, 18789.248430062646, 18888.52778680489, 18403.447896563124, 18482.630347393246, 18542.44372668822, 18546.81453185505, 18620.068996549846, 17230.224639891487, 17267.568310792332, 17349.392771252944, 17527.123643817504, 17563.209655565275, 13285.269310188032, 13352.0, 13355.799663005257, 13403.530876419278, 13472.191668499921, 13751.449942002208, 13904.165750055028, 13969.161850289016, 14121.2233327168, 14224.857751422353], "value": 9885.661029415409, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1378.6000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 105.0, 106.0, 105.0, 105.0, 107.0, 107.0, 108.0, 106.0, 106.0], "value": 106.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 116.0, 115.0, 113.0, 113.0, 133.0, 111.0, 115.0, 113.0, 112.0], "value": 115.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 162.0, 161.0, 165.0, 161.0, 161.0, 161.0, 162.0, 170.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 73.0, 73.0, 73.0, 73.0, 74.0, 73.0, 74.0, 75.0], "value": 73.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 78.0, 78.0, 78.0, 77.0, 78.0, 78.0, 79.0, 80.0, 78.0], "value": 78.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 116.0, 109.0, 114.0, 114.0, 114.0, 113.0, 114.0, 115.0, 113.0], "value": 113.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 149.0, 149.0, 149.0, 150.0, 148.0, 149.0, 151.0, 150.0], "value": 149.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 94.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 45.0, 44.0, 44.0, 45.0, 45.0, 45.0, 43.0, 45.0, 44.0], "value": 44.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 38.0, 37.0, 36.0, 37.0, 38.0, 36.0, 37.0, 37.0, 37.0], "value": 37.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 71.0, 72.0, 72.0, 72.0, 72.0, 72.0, 72.0, 72.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 116.0, 121.0, 111.0, 107.0, 111.0, 117.0, 116.0, 107.0], "value": 112.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 156.0, 157.0, 154.0, 166.0, 155.0, 162.0, 152.0, 157.0], "value": 158.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 59.0, 59.0, 65.0, 61.0, 59.0, 61.0, 58.0, 60.0], "value": 60.5, "unit": "score"}]}]} 04:07:15 INFO - Return code: 0 04:07:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:07:15 INFO - # TBPL SUCCESS # 04:07:15 INFO - Running post-action listener: _package_coverage_data 04:07:15 INFO - Running post-action listener: _resource_record_post_action 04:07:15 INFO - [mozharness: 2017-09-11 11:07:15.565334Z] Finished run-tests step (success) 04:07:15 INFO - Running post-run listener: _resource_record_post_run 04:07:15 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 8192; Write bytes: 290906112; Read time: 140; Write time: 147644 04:07:15 INFO - TinderboxPrint: CPU usage
13.1% 04:07:15 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 140 04:07:15 INFO - TinderboxPrint: I/O write bytes / time
290,906,112 / 147,644 04:07:15 INFO - TinderboxPrint: CPU idle
8,213.4 (86.9%) 04:07:15 INFO - TinderboxPrint: CPU user
1,185.4 (12.5%) 04:07:15 INFO - TinderboxPrint: Swap in / out
0 / 0 04:07:15 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 9166848; Read time: 0; Write time: 808 04:07:15 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:07:15 INFO - run-tests - Wall time: 1168s; CPU: 13.0%; Read bytes: 8192; Write bytes: 281739264; Read time: 140; Write time: 146780 04:07:16 INFO - Running post-run listener: _upload_blobber_files 04:07:16 INFO - Blob upload gear active. 04:07:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:07:16 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 04:07:16 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'] 04:07:16 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 04:07:16 INFO - (blobuploader) - INFO - Open directory for files ... 04:07:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:07:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:07:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:07:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:07:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:07:17 INFO - (blobuploader) - INFO - Done attempting. 04:07:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:07:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:07:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:07:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:07:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:07:18 INFO - (blobuploader) - INFO - Done attempting. 04:07:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:07:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:07:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:07:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:07:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:07:25 INFO - (blobuploader) - INFO - Done attempting. 04:07:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:07:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:07:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:07:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:07:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:07:26 INFO - (blobuploader) - INFO - Done attempting. 04:07:26 INFO - (blobuploader) - INFO - Iteration through files over. 04:07:26 INFO - Return code: 0 04:07:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:07:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:07:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa86328ed01f10ef48b5a0077a3d580b8484a78ea0dc3df88d2e060c47d324733ca57d93307490ea3c9a27d42219f9292a31a9430bf8a69272814b6e7cd4294e", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8e8fd023de4a4dcfa4620525ce000bdbf6540c876f26dcdcfc68696ad85d66c1a503df92ed62ec22c1087a9a0104cd9de14e9727bf8206e7abf48c865484542", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0cac0e8eefd989b748264a943c85973f7390b1d975c09134fb437062fe6d825bcccacd29a16030ec03f1158fec487c4466c2ec983c5dc2658b640ffbffea77d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22867e546b9d3f2c05db669f73c77239835baed097fbdb6baa9ec5dde7934e2dd70a4fae03bd4173a1eb5eb6f29ca0024f0e776ba550a863d3282dbf8f76fb8a"} 04:07:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:07:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:07:26 INFO - Contents: 04:07:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa86328ed01f10ef48b5a0077a3d580b8484a78ea0dc3df88d2e060c47d324733ca57d93307490ea3c9a27d42219f9292a31a9430bf8a69272814b6e7cd4294e", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8e8fd023de4a4dcfa4620525ce000bdbf6540c876f26dcdcfc68696ad85d66c1a503df92ed62ec22c1087a9a0104cd9de14e9727bf8206e7abf48c865484542", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0cac0e8eefd989b748264a943c85973f7390b1d975c09134fb437062fe6d825bcccacd29a16030ec03f1158fec487c4466c2ec983c5dc2658b640ffbffea77d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22867e546b9d3f2c05db669f73c77239835baed097fbdb6baa9ec5dde7934e2dd70a4fae03bd4173a1eb5eb6f29ca0024f0e776ba550a863d3282dbf8f76fb8a"} 04:07:26 INFO - Running post-run listener: copy_logs_to_upload_dir 04:07:26 INFO - Copying logs to upload dir... 04:07:26 INFO - mkdir: /builds/slave/test/build/upload/logs 04:07:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.244681 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-09-11 04:07:26.718996) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-11 04:07:26.726705) ========= 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=ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa86328ed01f10ef48b5a0077a3d580b8484a78ea0dc3df88d2e060c47d324733ca57d93307490ea3c9a27d42219f9292a31a9430bf8a69272814b6e7cd4294e", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8e8fd023de4a4dcfa4620525ce000bdbf6540c876f26dcdcfc68696ad85d66c1a503df92ed62ec22c1087a9a0104cd9de14e9727bf8206e7abf48c865484542", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0cac0e8eefd989b748264a943c85973f7390b1d975c09134fb437062fe6d825bcccacd29a16030ec03f1158fec487c4466c2ec983c5dc2658b640ffbffea77d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22867e546b9d3f2c05db669f73c77239835baed097fbdb6baa9ec5dde7934e2dd70a4fae03bd4173a1eb5eb6f29ca0024f0e776ba550a863d3282dbf8f76fb8a"} build_url:https://queue.taskcluster.net/v1/task/XZ_hBdiHR7SWWTd1kz6MXA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011332 build_url: 'https://queue.taskcluster.net/v1/task/XZ_hBdiHR7SWWTd1kz6MXA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa86328ed01f10ef48b5a0077a3d580b8484a78ea0dc3df88d2e060c47d324733ca57d93307490ea3c9a27d42219f9292a31a9430bf8a69272814b6e7cd4294e", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8e8fd023de4a4dcfa4620525ce000bdbf6540c876f26dcdcfc68696ad85d66c1a503df92ed62ec22c1087a9a0104cd9de14e9727bf8206e7abf48c865484542", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0cac0e8eefd989b748264a943c85973f7390b1d975c09134fb437062fe6d825bcccacd29a16030ec03f1158fec487c4466c2ec983c5dc2658b640ffbffea77d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22867e546b9d3f2c05db669f73c77239835baed097fbdb6baa9ec5dde7934e2dd70a4fae03bd4173a1eb5eb6f29ca0024f0e776ba550a863d3282dbf8f76fb8a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-11 04:07:26.755162) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 04:07:26.755499) ========= 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=ca619d3d7d62c99d832f7b8a000001c4-1505126809.605881-144884332 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004461 ========= master_lag: 0.18 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 04:07:26.940114) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-11 04:07:26.940519) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-11 04:07:26.941279) ========= ========= Total master_lag: 0.31 =========