builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-072 starttime: 1503969804.4 results: success (0) revision: 1b4c59eef820b46eb0037aca68f83a15088db45f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:24.401618) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:24.402018) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:24.402303) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:24.418235) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:24.418510) ========= 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=199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-28 18:23:24-- 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% 225M=0s 2017-08-28 18:23:24 (225 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.116324 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:24.550113) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:24.550453) ========= 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=199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022061 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:24.600320) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:24.600654) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1b4c59eef820b46eb0037aca68f83a15088db45f --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 1b4c59eef820b46eb0037aca68f83a15088db45f --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=199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-28 18:23:24,810 truncating revision to first 12 chars 2017-08-28 18:23:24,810 Setting DEBUG logging. 2017-08-28 18:23:24,810 attempt 1/10 2017-08-28 18:23:24,810 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1b4c59eef820?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-28 18:23:25,370 unpacking tar archive at: mozilla-central-1b4c59eef820/testing/mozharness/ program finished with exit code 0 elapsedTime=0.864091 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:25.479072) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:25.479376) ========= script_repo_revision: 1b4c59eef820b46eb0037aca68f83a15088db45f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:25.479737) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:25.479993) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:25.493155) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 31 secs) (at 2017-08-28 18:23:25.493425) ========= /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=199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:23:25 INFO - MultiFileLogger online at 20170828 18:23:25 in /builds/slave/test 18:23:25 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 18:23:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:23:25 INFO - {'append_to_log': False, 18:23:25 INFO - 'base_work_dir': '/builds/slave/test', 18:23:25 INFO - 'blob_upload_branch': 'Firefox', 18:23:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:23:25 INFO - 'branch': 'Firefox', 18:23:25 INFO - 'buildbot_json_path': 'buildprops.json', 18:23:25 INFO - 'code_coverage': False, 18:23:25 INFO - 'config_files': ('talos/linux_config.py',), 18:23:25 INFO - 'default_actions': ('clobber', 18:23:25 INFO - 'read-buildbot-config', 18:23:25 INFO - 'download-and-extract', 18:23:25 INFO - 'populate-webroot', 18:23:25 INFO - 'create-virtualenv', 18:23:25 INFO - 'install', 18:23:25 INFO - 'setup-mitmproxy', 18:23:25 INFO - 'run-tests'), 18:23:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:23:25 INFO - 'disable_ccov_upload': False, 18:23:25 INFO - 'download_minidump_stackwalk': True, 18:23:25 INFO - 'download_symbols': 'ondemand', 18:23:25 INFO - 'enable_stylo': False, 18:23:25 INFO - 'enable_webrender': False, 18:23:25 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:23:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:23:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:23:25 INFO - 'gecko_profile': False, 18:23:25 INFO - 'gecko_profile_interval': 0, 18:23:25 INFO - 'installer_path': 'installer.exe', 18:23:25 INFO - 'log_level': 'info', 18:23:25 INFO - 'log_name': 'talos', 18:23:25 INFO - 'log_to_console': True, 18:23:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:23:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:23:25 INFO - 'opt_config_files': (), 18:23:25 INFO - 'pip_index': False, 18:23:25 INFO - 'suite': 'dromaeojs-e10s', 18:23:25 INFO - 'system_bits': '32', 18:23:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:23:25 INFO - 'title': 'talos-linux64-ix-072', 18:23:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:23:25 INFO - 'use_talos_json': True, 18:23:25 INFO - 'verify': 'False', 18:23:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:23:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:23:25 INFO - 'work_dir': 'build'} 18:23:25 INFO - [mozharness: 2017-08-29 01:23:25.837630Z] Running clobber step. 18:23:25 INFO - Running pre-action listener: _resource_record_pre_action 18:23:25 INFO - Running main action method: clobber 18:23:25 INFO - rmtree: /builds/slave/test/build 18:23:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:23:26 INFO - Running post-action listener: _resource_record_post_action 18:23:26 INFO - [mozharness: 2017-08-29 01:23:26.846858Z] Finished clobber step (success) 18:23:26 INFO - [mozharness: 2017-08-29 01:23:26.846954Z] Running read-buildbot-config step. 18:23:26 INFO - Running pre-action listener: _resource_record_pre_action 18:23:26 INFO - Running main action method: read_buildbot_config 18:23:26 INFO - Using buildbot properties: 18:23:26 INFO - { 18:23:26 INFO - "project": "", 18:23:26 INFO - "product": "firefox", 18:23:26 INFO - "who": "kwierso@gmail.com", 18:23:26 INFO - "installer_path": "public/build/target.tar.bz2", 18:23:26 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 18:23:26 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:23:26 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 18:23:26 INFO - "stage_platform": "linux64-qr", 18:23:26 INFO - "basedir": "/builds/slave/test", 18:23:26 INFO - "buildnumber": 20, 18:23:26 INFO - "platform": "ubuntu64_hw_qr", 18:23:26 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:23:26 INFO - "slavebuilddir": "test", 18:23:26 INFO - "taskId": "Simp7n1SSLCybKIw9yboWg", 18:23:26 INFO - "branch": "mozilla-central", 18:23:26 INFO - "script_repo_revision": "production", 18:23:26 INFO - "revision": "1b4c59eef820b46eb0037aca68f83a15088db45f", 18:23:26 INFO - "slavename": "talos-linux64-ix-072", 18:23:26 INFO - "repo_path": "mozilla-central" 18:23:26 INFO - } 18:23:26 INFO - Finding installer, test and symbols from parent task. 18:23:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Simp7n1SSLCybKIw9yboWg'}, attempt #1 18:23:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Simp7n1SSLCybKIw9yboWg'}, attempt #1 18:23:27 INFO - Task dependencies: A3hmUCGWRMeTZ6zQMbT4dg 18:23:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg'}, attempt #1 18:23:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.tar.bz2 18:23:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.test_packages.json 18:23:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.crashreporter-symbols.zip 18:23:28 INFO - Running post-action listener: _resource_record_post_action 18:23:28 INFO - [mozharness: 2017-08-29 01:23:28.179885Z] Finished read-buildbot-config step (success) 18:23:28 INFO - [mozharness: 2017-08-29 01:23:28.180106Z] Running download-and-extract step. 18:23:28 INFO - Running pre-action listener: _resource_record_pre_action 18:23:28 INFO - Running main action method: download_and_extract 18:23:28 INFO - mkdir: /builds/slave/test/build/tests 18:23:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:28 INFO - trying https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.test_packages.json 18:23:28 INFO - Downloading https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:23:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:23:28 INFO - Downloaded 989 bytes. 18:23:28 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:23:28 INFO - Using the following test package requirements: 18:23:28 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:23:28 INFO - u'common': [u'target.common.tests.zip'], 18:23:28 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:23:28 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:23:28 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:23:28 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:23:28 INFO - u'mozbase': [u'target.common.tests.zip'], 18:23:28 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:23:28 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:23:28 INFO - u'web-platform': [u'target.common.tests.zip', 18:23:28 INFO - u'target.web-platform.tests.tar.gz'], 18:23:28 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:23:28 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:23:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.common.tests.zip 18:23:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:23:28 INFO - Fetch https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.common.tests.zip into memory 18:23:29 INFO - Content-Length response header: 54977777 18:23:29 INFO - Bytes received: 54977777 18:23:41 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:23:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.common.tests.zip 18:23:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:23:41 INFO - Fetch https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.common.tests.zip into memory 18:23:42 INFO - Content-Length response header: 54977777 18:23:42 INFO - Bytes received: 54977777 18:23:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.talos.tests.zip 18:23:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:23:54 INFO - Fetch https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.talos.tests.zip into memory 18:23:55 INFO - Content-Length response header: 14496611 18:23:55 INFO - Bytes received: 14496611 18:23:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:55 INFO - trying https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.tar.bz2 18:23:55 INFO - Downloading https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:23:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:24:27 INFO - Downloaded 61258746 bytes. 18:24:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.tar.bz2 18:24:27 INFO - mkdir: /builds/slave/test/properties 18:24:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:24:27 INFO - Writing to file /builds/slave/test/properties/build_url 18:24:27 INFO - Contents: 18:24:27 INFO - build_url:https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.tar.bz2 18:24:27 INFO - Running post-action listener: _resource_record_post_action 18:24:27 INFO - Running post-action listener: find_tests_for_verification 18:24:27 INFO - Running post-action listener: set_extra_try_arguments 18:24:27 INFO - [mozharness: 2017-08-29 01:24:27.358152Z] Finished download-and-extract step (success) 18:24:27 INFO - [mozharness: 2017-08-29 01:24:27.358379Z] Running populate-webroot step. 18:24:27 INFO - Running pre-action listener: _resource_record_pre_action 18:24:27 INFO - Running main action method: populate_webroot 18:24:27 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:24:27 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 18:24:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:24:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:24:27 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 18:24:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:24:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:24:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:24:27 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:24:27 INFO - 'talos_options': ['--stylo'], 18:24:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:24:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:24:27 INFO - 'tests': ['damp', 'tps']}, 18:24:27 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:24:27 INFO - 'talos_options': ['--stylo'], 18:24:27 INFO - 'tests': ['damp', 'tps']}, 18:24:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:24:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:24:27 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 18:24:27 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:24:27 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:24:27 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:24:27 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:24:27 INFO - 'talos_options': ['--stylo'], 18:24:27 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:24:27 INFO - 'other-e10s': {'tests': ['a11yr', 18:24:27 INFO - 'ts_paint', 18:24:27 INFO - 'tpaint', 18:24:27 INFO - 'sessionrestore', 18:24:27 INFO - 'sessionrestore_many_windows', 18:24:27 INFO - 'sessionrestore_no_auto_restore', 18:24:27 INFO - 'tabpaint']}, 18:24:27 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 18:24:27 INFO - 'tests': ['a11yr', 18:24:27 INFO - 'ts_paint', 18:24:27 INFO - 'tpaint', 18:24:27 INFO - 'sessionrestore', 18:24:27 INFO - 'sessionrestore_many_windows', 18:24:27 INFO - 'sessionrestore_no_auto_restore', 18:24:27 INFO - 'tabpaint']}, 18:24:27 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:24:27 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:24:27 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 18:24:27 INFO - 'tests': ['bloom_basic_singleton']}, 18:24:27 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 18:24:27 INFO - 'tests': ['bloom_basic']}, 18:24:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:24:27 INFO - 'tsvgr_opacity', 18:24:27 INFO - 'tart', 18:24:27 INFO - 'tscrollx', 18:24:27 INFO - 'tsvg_static']}, 18:24:27 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 18:24:27 INFO - 'tests': ['tsvgx', 18:24:27 INFO - 'tsvgr_opacity', 18:24:27 INFO - 'tart', 18:24:27 INFO - 'tscrollx', 18:24:27 INFO - 'tsvg_static']}, 18:24:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:24:27 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:24:27 INFO - 'talos_options': ['--stylo'], 18:24:27 INFO - 'tests': ['tp5o']}, 18:24:27 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:24:27 INFO - 'talos_options': ['--mitmproxy', 18:24:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:24:27 INFO - '--firstNonBlankPaint'], 18:24:27 INFO - 'tests': ['tp6_google', 18:24:27 INFO - 'tp6_youtube', 18:24:27 INFO - 'tp6_amazon', 18:24:27 INFO - 'tp6_facebook']}, 18:24:27 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:24:27 INFO - 'talos_options': ['--stylo', 18:24:27 INFO - '--mitmproxy', 18:24:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:24:27 INFO - '--firstNonBlankPaint'], 18:24:27 INFO - 'tests': ['tp6_google', 18:24:27 INFO - 'tp6_youtube', 18:24:27 INFO - 'tp6_amazon', 18:24:27 INFO - 'tp6_facebook']}, 18:24:27 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:24:27 INFO - 'talos_options': ['--stylo', 18:24:27 INFO - '--stylo-threads=1', 18:24:27 INFO - '--mitmproxy', 18:24:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:24:27 INFO - '--firstNonBlankPaint'], 18:24:27 INFO - 'tests': ['tp6_google', 18:24:27 INFO - 'tp6_youtube', 18:24:27 INFO - 'tp6_amazon', 18:24:27 INFO - 'tp6_facebook']}, 18:24:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:24:27 INFO - 'talos_options': ['--xperf_path', 18:24:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:24:27 INFO - 'tests': ['tp5n']}, 18:24:27 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:24:27 INFO - 'talos_options': ['--stylo', 18:24:27 INFO - '--xperf_path', 18:24:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:24:27 INFO - 'tests': ['tp5n']}}} 18:24:27 INFO - Running post-action listener: _resource_record_post_action 18:24:27 INFO - [mozharness: 2017-08-29 01:24:27.378383Z] Finished populate-webroot step (success) 18:24:27 INFO - [mozharness: 2017-08-29 01:24:27.378471Z] Running create-virtualenv step. 18:24:27 INFO - Running pre-action listener: _resource_record_pre_action 18:24:27 INFO - Running main action method: create_virtualenv 18:24:27 INFO - Creating virtualenv /builds/slave/test/build/venv 18:24:27 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:24:27 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:24:27 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:24:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:24:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:24:28 INFO - Installing distribute.............................................................................................................................................................................................done. 18:24:28 INFO - Installing pip...............done. 18:24:28 INFO - Return code: 0 18:24:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:24:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:24:28 INFO - Reading from file tmpfile_stdout 18:24:28 INFO - Output received: 18:24:28 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:24:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:24:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1274a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1417f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1415130>, '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 0x1416030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14164c0>, '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': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:24:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:24:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:24:28 INFO - Using env: {'DISPLAY': ':0', 18:24:28 INFO - 'HOME': '/home/cltbld', 18:24:28 INFO - 'LANG': 'en_US.UTF-8', 18:24:28 INFO - 'LANGUAGE': 'en_US:en', 18:24:28 INFO - 'LOGNAME': 'cltbld', 18:24:28 INFO - 'MAIL': '/var/mail/cltbld', 18:24:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:28 INFO - 'MOZ_NO_REMOTE': '1', 18:24:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:28 INFO - 'NO_EM_RESTART': '1', 18:24:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:28 INFO - 'PWD': '/builds/slave/test', 18:24:28 INFO - 'SHELL': '/bin/bash', 18:24:28 INFO - 'SHLVL': '1', 18:24:28 INFO - 'TERM': 'linux', 18:24:28 INFO - 'TMOUT': '86400', 18:24:28 INFO - 'USER': 'cltbld', 18:24:28 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 18:24:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:28 INFO - '_': '/tools/buildbot/bin/python'} 18:24:29 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:24:29 INFO - Downloading/unpacking pip>=1.5 18:24:29 INFO - Running setup.py egg_info for package pip 18:24:29 INFO - warning: no files found matching 'pip/cacert.pem' 18:24:29 INFO - warning: no files found matching '*.html' under directory 'docs' 18:24:29 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:24:29 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:24:29 INFO - Installing collected packages: pip 18:24:29 INFO - Found existing installation: pip 1.1 18:24:29 INFO - Uninstalling pip: 18:24:29 INFO - Successfully uninstalled pip 18:24:29 INFO - Running setup.py install for pip 18:24:29 INFO - warning: no files found matching 'pip/cacert.pem' 18:24:29 INFO - warning: no files found matching '*.html' under directory 'docs' 18:24:29 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:24:29 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:24:29 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:24:29 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:24:29 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:24:29 INFO - Successfully installed pip 18:24:29 INFO - Cleaning up... 18:24:29 INFO - Return code: 0 18:24:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1274a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1417f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1415130>, '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 0x1416030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14164c0>, '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': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:24:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:24:29 INFO - Using env: {'DISPLAY': ':0', 18:24:29 INFO - 'HOME': '/home/cltbld', 18:24:29 INFO - 'LANG': 'en_US.UTF-8', 18:24:29 INFO - 'LANGUAGE': 'en_US:en', 18:24:29 INFO - 'LOGNAME': 'cltbld', 18:24:29 INFO - 'MAIL': '/var/mail/cltbld', 18:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:29 INFO - 'MOZ_NO_REMOTE': '1', 18:24:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:29 INFO - 'NO_EM_RESTART': '1', 18:24:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:29 INFO - 'PWD': '/builds/slave/test', 18:24:29 INFO - 'SHELL': '/bin/bash', 18:24:29 INFO - 'SHLVL': '1', 18:24:29 INFO - 'TERM': 'linux', 18:24:29 INFO - 'TMOUT': '86400', 18:24:29 INFO - 'USER': 'cltbld', 18:24:29 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 18:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:29 INFO - '_': '/tools/buildbot/bin/python'} 18:24:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:30 INFO - Downloading/unpacking psutil>=3.1.1 18:24:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:24:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:24:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:24:31 INFO - Installing collected packages: psutil 18:24:31 INFO - Running setup.py install for psutil 18:24:31 INFO - building 'psutil._psutil_linux' extension 18:24:31 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:24:31 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:24:31 INFO - building 'psutil._psutil_posix' extension 18:24:31 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:24:31 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:24:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:24:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:24:31 INFO - Successfully installed psutil 18:24:31 INFO - Cleaning up... 18:24:31 INFO - Return code: 0 18:24:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:24:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1274a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1417f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1415130>, '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 0x1416030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14164c0>, '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': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:24:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:24:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:24:31 INFO - Using env: {'DISPLAY': ':0', 18:24:31 INFO - 'HOME': '/home/cltbld', 18:24:31 INFO - 'LANG': 'en_US.UTF-8', 18:24:31 INFO - 'LANGUAGE': 'en_US:en', 18:24:31 INFO - 'LOGNAME': 'cltbld', 18:24:31 INFO - 'MAIL': '/var/mail/cltbld', 18:24:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:31 INFO - 'MOZ_NO_REMOTE': '1', 18:24:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:31 INFO - 'NO_EM_RESTART': '1', 18:24:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:31 INFO - 'PWD': '/builds/slave/test', 18:24:31 INFO - 'SHELL': '/bin/bash', 18:24:31 INFO - 'SHLVL': '1', 18:24:31 INFO - 'TERM': 'linux', 18:24:31 INFO - 'TMOUT': '86400', 18:24:31 INFO - 'USER': 'cltbld', 18:24:31 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 18:24:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:31 INFO - '_': '/tools/buildbot/bin/python'} 18:24:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:24:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:24:33 INFO - Installing collected packages: mozsystemmonitor 18:24:33 INFO - Running setup.py install for mozsystemmonitor 18:24:33 INFO - Successfully installed mozsystemmonitor 18:24:33 INFO - Cleaning up... 18:24:33 INFO - Return code: 0 18:24:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:24:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:33 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 0x10a91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1274a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1417f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1415130>, '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 0x1416030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14164c0>, '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': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:24:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:24:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:24:33 INFO - Using env: {'DISPLAY': ':0', 18:24:33 INFO - 'HOME': '/home/cltbld', 18:24:33 INFO - 'LANG': 'en_US.UTF-8', 18:24:33 INFO - 'LANGUAGE': 'en_US:en', 18:24:33 INFO - 'LOGNAME': 'cltbld', 18:24:33 INFO - 'MAIL': '/var/mail/cltbld', 18:24:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:33 INFO - 'MOZ_NO_REMOTE': '1', 18:24:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:33 INFO - 'NO_EM_RESTART': '1', 18:24:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:33 INFO - 'PWD': '/builds/slave/test', 18:24:33 INFO - 'SHELL': '/bin/bash', 18:24:33 INFO - 'SHLVL': '1', 18:24:33 INFO - 'TERM': 'linux', 18:24:33 INFO - 'TMOUT': '86400', 18:24:33 INFO - 'USER': 'cltbld', 18:24:33 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 18:24:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:33 INFO - '_': '/tools/buildbot/bin/python'} 18:24:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:33 INFO - Downloading/unpacking jsonschema==2.5.1 18:24:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:24:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:34 INFO - Downloading functools32-3.2.3-2.tar.gz 18:24:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:24:34 INFO - warning: no files found matching '*.txt' 18:24:34 INFO - no previously-included directories found matching 'build' 18:24:34 INFO - no previously-included directories found matching 'dist' 18:24:34 INFO - no previously-included directories found matching '.git*' 18:24:34 INFO - Installing collected packages: jsonschema, functools32 18:24:34 INFO - Running setup.py install for functools32 18:24:34 INFO - warning: no files found matching '*.txt' 18:24:34 INFO - no previously-included directories found matching 'build' 18:24:34 INFO - no previously-included directories found matching 'dist' 18:24:34 INFO - no previously-included directories found matching '.git*' 18:24:34 INFO - Successfully installed jsonschema functools32 18:24:34 INFO - Cleaning up... 18:24:34 INFO - Return code: 0 18:24:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:24:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1274a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1417f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1415130>, '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 0x1416030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14164c0>, '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': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:24:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:24:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:24:34 INFO - Using env: {'DISPLAY': ':0', 18:24:34 INFO - 'HOME': '/home/cltbld', 18:24:34 INFO - 'LANG': 'en_US.UTF-8', 18:24:34 INFO - 'LANGUAGE': 'en_US:en', 18:24:34 INFO - 'LOGNAME': 'cltbld', 18:24:34 INFO - 'MAIL': '/var/mail/cltbld', 18:24:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:34 INFO - 'MOZ_NO_REMOTE': '1', 18:24:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:34 INFO - 'NO_EM_RESTART': '1', 18:24:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:34 INFO - 'PWD': '/builds/slave/test', 18:24:34 INFO - 'SHELL': '/bin/bash', 18:24:34 INFO - 'SHLVL': '1', 18:24:34 INFO - 'TERM': 'linux', 18:24:34 INFO - 'TMOUT': '86400', 18:24:34 INFO - 'USER': 'cltbld', 18:24:34 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 18:24:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:34 INFO - '_': '/tools/buildbot/bin/python'} 18:24:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:24:35 INFO - Cleaning up... 18:24:35 INFO - Return code: 0 18:24:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:24:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:35 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 0x10a91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1274a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1417f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1415130>, '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 0x1416030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14164c0>, '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': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:24:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:24:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:24:35 INFO - Using env: {'DISPLAY': ':0', 18:24:35 INFO - 'HOME': '/home/cltbld', 18:24:35 INFO - 'LANG': 'en_US.UTF-8', 18:24:35 INFO - 'LANGUAGE': 'en_US:en', 18:24:35 INFO - 'LOGNAME': 'cltbld', 18:24:35 INFO - 'MAIL': '/var/mail/cltbld', 18:24:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:35 INFO - 'MOZ_NO_REMOTE': '1', 18:24:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:35 INFO - 'NO_EM_RESTART': '1', 18:24:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:35 INFO - 'PWD': '/builds/slave/test', 18:24:35 INFO - 'SHELL': '/bin/bash', 18:24:35 INFO - 'SHLVL': '1', 18:24:35 INFO - 'TERM': 'linux', 18:24:35 INFO - 'TMOUT': '86400', 18:24:35 INFO - 'USER': 'cltbld', 18:24:35 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 18:24:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:35 INFO - '_': '/tools/buildbot/bin/python'} 18:24:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:35 INFO - Downloading/unpacking blobuploader==1.2.4 18:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:36 INFO - Downloading blobuploader-1.2.4.tar.gz 18:24:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:24:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:24:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:37 INFO - Downloading docopt-0.6.1.tar.gz 18:24:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:24:37 INFO - Installing collected packages: blobuploader, requests, docopt 18:24:37 INFO - Running setup.py install for blobuploader 18:24:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:24:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:24:37 INFO - Running setup.py install for requests 18:24:37 INFO - Running setup.py install for docopt 18:24:37 INFO - Successfully installed blobuploader requests docopt 18:24:37 INFO - Cleaning up... 18:24:37 INFO - Return code: 0 18:24:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:37 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 0x10a91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1274a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1417f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1415130>, '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 0x1416030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14164c0>, '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': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:24:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:24:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:24:37 INFO - Using env: {'DISPLAY': ':0', 18:24:37 INFO - 'HOME': '/home/cltbld', 18:24:37 INFO - 'LANG': 'en_US.UTF-8', 18:24:37 INFO - 'LANGUAGE': 'en_US:en', 18:24:37 INFO - 'LOGNAME': 'cltbld', 18:24:37 INFO - 'MAIL': '/var/mail/cltbld', 18:24:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:37 INFO - 'MOZ_NO_REMOTE': '1', 18:24:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:37 INFO - 'NO_EM_RESTART': '1', 18:24:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:37 INFO - 'PWD': '/builds/slave/test', 18:24:37 INFO - 'SHELL': '/bin/bash', 18:24:37 INFO - 'SHLVL': '1', 18:24:37 INFO - 'TERM': 'linux', 18:24:37 INFO - 'TMOUT': '86400', 18:24:37 INFO - 'USER': 'cltbld', 18:24:37 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 18:24:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:37 INFO - '_': '/tools/buildbot/bin/python'} 18:24:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:24:38 INFO - Running setup.py (path:/tmp/pip-VrSKGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:24:38 INFO - Running setup.py (path:/tmp/pip-mUmVnq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:24:38 INFO - Running setup.py (path:/tmp/pip-4yzCay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:24:38 INFO - Running setup.py (path:/tmp/pip-TZ7QqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:24:38 INFO - Running setup.py (path:/tmp/pip-Y50VDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:24:38 INFO - Running setup.py (path:/tmp/pip-ZrRnQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:24:38 INFO - Running setup.py (path:/tmp/pip-HntzR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:24:38 INFO - Running setup.py (path:/tmp/pip-rZemJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:24:38 INFO - Running setup.py (path:/tmp/pip-5N60tE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:24:38 INFO - Running setup.py (path:/tmp/pip-UhTeDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:24:38 INFO - Running setup.py (path:/tmp/pip-ejNR43-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:24:38 INFO - Running setup.py (path:/tmp/pip-_4a9EY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:24:38 INFO - Running setup.py (path:/tmp/pip-YBKcCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:24:38 INFO - Running setup.py (path:/tmp/pip-erX0z_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:39 INFO - Running setup.py (path:/tmp/pip-Ngahqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:24:39 INFO - Running setup.py (path:/tmp/pip-lIA8Yg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:24:39 INFO - Running setup.py (path:/tmp/pip-VQ8DJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:24:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:24:39 INFO - Running setup.py install for manifestparser 18:24:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:24:39 INFO - Running setup.py install for mozcrash 18:24:39 INFO - Running setup.py install for mozdebug 18:24:39 INFO - Running setup.py install for mozdevice 18:24:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:24:39 INFO - Running setup.py install for mozfile 18:24:39 INFO - Running setup.py install for mozhttpd 18:24:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:24:39 INFO - Running setup.py install for mozinfo 18:24:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:24:39 INFO - Running setup.py install for mozInstall 18:24:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:24:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:24:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:24:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:24:40 INFO - Running setup.py install for mozleak 18:24:40 INFO - Running setup.py install for mozlog 18:24:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:24:40 INFO - Running setup.py install for moznetwork 18:24:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:24:40 INFO - Running setup.py install for mozprocess 18:24:40 INFO - Running setup.py install for mozprofile 18:24:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:24:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:24:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:24:40 INFO - Running setup.py install for mozrunner 18:24:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:24:40 INFO - Running setup.py install for mozscreenshot 18:24:40 INFO - Running setup.py install for moztest 18:24:40 INFO - Running setup.py install for mozversion 18:24:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:24:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:24:40 INFO - Cleaning up... 18:24:40 INFO - Return code: 0 18:24:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1274a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1417f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1415130>, '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 0x1416030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14164c0>, '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': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:24:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:24:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:24:41 INFO - Using env: {'DISPLAY': ':0', 18:24:41 INFO - 'HOME': '/home/cltbld', 18:24:41 INFO - 'LANG': 'en_US.UTF-8', 18:24:41 INFO - 'LANGUAGE': 'en_US:en', 18:24:41 INFO - 'LOGNAME': 'cltbld', 18:24:41 INFO - 'MAIL': '/var/mail/cltbld', 18:24:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:41 INFO - 'MOZ_NO_REMOTE': '1', 18:24:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:41 INFO - 'NO_EM_RESTART': '1', 18:24:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:41 INFO - 'PWD': '/builds/slave/test', 18:24:41 INFO - 'SHELL': '/bin/bash', 18:24:41 INFO - 'SHLVL': '1', 18:24:41 INFO - 'TERM': 'linux', 18:24:41 INFO - 'TMOUT': '86400', 18:24:41 INFO - 'USER': 'cltbld', 18:24:41 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 18:24:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:41 INFO - '_': '/tools/buildbot/bin/python'} 18:24:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:24:41 INFO - Running setup.py (path:/tmp/pip-_pQ1H2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:24:41 INFO - Running setup.py (path:/tmp/pip-rP2Tzf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:24:41 INFO - Running setup.py (path:/tmp/pip-bAgstG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:24:41 INFO - Running setup.py (path:/tmp/pip-f1WIJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:24:41 INFO - Running setup.py (path:/tmp/pip-vdGszr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:24:41 INFO - Running setup.py (path:/tmp/pip-nfE8Fu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:24:41 INFO - Running setup.py (path:/tmp/pip-9yHgtI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:24:41 INFO - Running setup.py (path:/tmp/pip-KpXJws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:24:41 INFO - Running setup.py (path:/tmp/pip-CEvQ6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:24:41 INFO - Running setup.py (path:/tmp/pip-2E3MAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:24:41 INFO - Running setup.py (path:/tmp/pip-rpZAeM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:24:42 INFO - Running setup.py (path:/tmp/pip-5zDudp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:24:42 INFO - Running setup.py (path:/tmp/pip-lBhixl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:24:42 INFO - Running setup.py (path:/tmp/pip-Jmd4J2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:42 INFO - Running setup.py (path:/tmp/pip-ilqD7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:24:42 INFO - Running setup.py (path:/tmp/pip-QTdSOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:24:42 INFO - Running setup.py (path:/tmp/pip-Wa0ofR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:24:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:44 INFO - Downloading blessings-1.6.tar.gz 18:24:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:24:44 INFO - Installing collected packages: blessings 18:24:44 INFO - Running setup.py install for blessings 18:24:44 INFO - Successfully installed blessings 18:24:44 INFO - Cleaning up... 18:24:44 INFO - Return code: 0 18:24:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:24:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:24:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:24:45 INFO - Reading from file tmpfile_stdout 18:24:45 INFO - Current package versions: 18:24:45 INFO - argparse == 1.2.1 18:24:45 INFO - blessings == 1.6 18:24:45 INFO - blobuploader == 1.2.4 18:24:45 INFO - docopt == 0.6.1 18:24:45 INFO - functools32 == 3.2.3-2 18:24:45 INFO - jsonschema == 2.5.1 18:24:45 INFO - manifestparser == 1.1 18:24:45 INFO - mozInstall == 1.13 18:24:45 INFO - mozcrash == 1.0 18:24:45 INFO - mozdebug == 0.1 18:24:45 INFO - mozdevice == 0.51 18:24:45 INFO - mozfile == 1.2 18:24:45 INFO - mozhttpd == 0.7 18:24:45 INFO - mozinfo == 0.10 18:24:45 INFO - mozleak == 0.1 18:24:45 INFO - mozlog == 3.5 18:24:45 INFO - moznetwork == 0.27 18:24:45 INFO - mozprocess == 0.25 18:24:45 INFO - mozprofile == 0.28 18:24:45 INFO - mozrunner == 6.13 18:24:45 INFO - mozscreenshot == 0.1 18:24:45 INFO - mozsystemmonitor == 0.3 18:24:45 INFO - moztest == 0.8 18:24:45 INFO - mozversion == 1.4 18:24:45 INFO - psutil == 3.1.1 18:24:45 INFO - requests == 1.2.3 18:24:45 INFO - wsgiref == 0.1.2 18:24:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:45 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 0x10a91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1274a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1417f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1415130>, '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 0x1416030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14164c0>, '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': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:24:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:24:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:24:45 INFO - Using env: {'DISPLAY': ':0', 18:24:45 INFO - 'HOME': '/home/cltbld', 18:24:45 INFO - 'LANG': 'en_US.UTF-8', 18:24:45 INFO - 'LANGUAGE': 'en_US:en', 18:24:45 INFO - 'LOGNAME': 'cltbld', 18:24:45 INFO - 'MAIL': '/var/mail/cltbld', 18:24:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:45 INFO - 'MOZ_NO_REMOTE': '1', 18:24:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:45 INFO - 'NO_EM_RESTART': '1', 18:24:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:45 INFO - 'PWD': '/builds/slave/test', 18:24:45 INFO - 'SHELL': '/bin/bash', 18:24:45 INFO - 'SHLVL': '1', 18:24:45 INFO - 'TERM': 'linux', 18:24:45 INFO - 'TMOUT': '86400', 18:24:45 INFO - 'USER': 'cltbld', 18:24:45 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 18:24:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:45 INFO - '_': '/tools/buildbot/bin/python'} 18:24:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:24:45 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:24:46 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:24:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:24:46 INFO - Installing collected packages: simplejson 18:24:46 INFO - Running setup.py install for simplejson 18:24:46 INFO - building 'simplejson._speedups' extension 18:24:46 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 18:24:47 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 18:24:47 INFO - Successfully installed simplejson 18:24:47 INFO - Cleaning up... 18:24:47 INFO - Return code: 0 18:24:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:24: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')]} 18:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24: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')]} 18:24:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:47 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 0x10a91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1274a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1417f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1415130>, '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 0x1416030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14164c0>, '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': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:24:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:24:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:24:47 INFO - Using env: {'DISPLAY': ':0', 18:24:47 INFO - 'HOME': '/home/cltbld', 18:24:47 INFO - 'LANG': 'en_US.UTF-8', 18:24:47 INFO - 'LANGUAGE': 'en_US:en', 18:24:47 INFO - 'LOGNAME': 'cltbld', 18:24:47 INFO - 'MAIL': '/var/mail/cltbld', 18:24:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:47 INFO - 'MOZ_NO_REMOTE': '1', 18:24:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:47 INFO - 'NO_EM_RESTART': '1', 18:24:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:47 INFO - 'PWD': '/builds/slave/test', 18:24:47 INFO - 'SHELL': '/bin/bash', 18:24:47 INFO - 'SHLVL': '1', 18:24:47 INFO - 'TERM': 'linux', 18:24:47 INFO - 'TMOUT': '86400', 18:24:47 INFO - 'USER': 'cltbld', 18:24:47 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 18:24:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:47 INFO - '_': '/tools/buildbot/bin/python'} 18:24:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:24:47 INFO - Cleaning up... 18:24:47 INFO - Return code: 0 18:24:47 INFO - Running post-action listener: _resource_record_post_action 18:24:47 INFO - Running post-action listener: _start_resource_monitoring 18:24:47 INFO - Starting resource monitoring. 18:24:47 INFO - [mozharness: 2017-08-29 01:24:47.736387Z] Finished create-virtualenv step (success) 18:24:47 INFO - [mozharness: 2017-08-29 01:24:47.736899Z] Running install step. 18:24:47 INFO - Running pre-action listener: _resource_record_pre_action 18:24:47 INFO - Running main action method: install 18:24:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:24:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:24:47 INFO - Reading from file tmpfile_stdout 18:24:47 INFO - Detecting whether we're running mozinstall >=1.0... 18:24:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:24:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:24:48 INFO - Reading from file tmpfile_stdout 18:24:48 INFO - Output received: 18:24:48 INFO - Usage: mozinstall [options] installer 18:24:48 INFO - Options: 18:24:48 INFO - -h, --help show this help message and exit 18:24:48 INFO - -d DEST, --destination=DEST 18:24:48 INFO - Directory to install application into. [default: 18:24:48 INFO - "/builds/slave/test"] 18:24:48 INFO - --app=APP Application being installed. [default: firefox] 18:24:48 INFO - mkdir: /builds/slave/test/build/application 18:24:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:24:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:25:05 INFO - Reading from file tmpfile_stdout 18:25:05 INFO - Output received: 18:25:05 INFO - /builds/slave/test/build/application/firefox/firefox 18:25:05 INFO - Running post-action listener: _resource_record_post_action 18:25:05 INFO - [mozharness: 2017-08-29 01:25:05.774423Z] Finished install step (success) 18:25:05 INFO - [mozharness: 2017-08-29 01:25:05.774569Z] Running setup-mitmproxy step. 18:25:05 INFO - Running pre-action listener: _resource_record_pre_action 18:25:05 INFO - Running main action method: setup_mitmproxy 18:25:05 INFO - Skipping: mitmproxy is not required 18:25:05 INFO - Running post-action listener: _resource_record_post_action 18:25:05 INFO - [mozharness: 2017-08-29 01:25:05.775120Z] Finished setup-mitmproxy step (success) 18:25:05 INFO - [mozharness: 2017-08-29 01:25:05.775248Z] Running run-tests step. 18:25:05 INFO - Running pre-action listener: _resource_record_pre_action 18:25:05 INFO - Running pre-action listener: _set_gcov_prefix 18:25:05 INFO - Running main action method: run_tests 18:25:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:25:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:25:05 INFO - Python 2.7.3 18:25:05 INFO - Return code: 0 18:25:05 INFO - grabbing minidump binary from tooltool 18:25:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:05 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 0x1415130>, '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 0x1416030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14164c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:25:05 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:25:05 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:25:05 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:25:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:25:05 INFO - Return code: 0 18:25:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:25:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:25:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:25:05 INFO - ENV: RUST_BACKTRACE is now full 18:25:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:25:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:25:05 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/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-072', '--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 18:25:05 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/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-072 --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 18:25:05 INFO - Using env: {'DISPLAY': ':0', 18:25:05 INFO - 'HOME': '/home/cltbld', 18:25:05 INFO - 'LANG': 'en_US.UTF-8', 18:25:05 INFO - 'LANGUAGE': 'en_US:en', 18:25:05 INFO - 'LOGNAME': 'cltbld', 18:25:05 INFO - 'MAIL': '/var/mail/cltbld', 18:25:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:25:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:25:05 INFO - 'MOZ_ACCELERATED': '1', 18:25:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:05 INFO - 'MOZ_NO_REMOTE': '1', 18:25:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:25:05 INFO - 'MOZ_WEBRENDER': '1', 18:25:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:05 INFO - 'NO_EM_RESTART': '1', 18:25:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:05 INFO - 'PWD': '/builds/slave/test', 18:25:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:25:05 INFO - 'RUST_BACKTRACE': 'full', 18:25:05 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:25:05 INFO - 'SHELL': '/bin/bash', 18:25:05 INFO - 'SHLVL': '1', 18:25:05 INFO - 'TERM': 'linux', 18:25:05 INFO - 'TMOUT': '86400', 18:25:05 INFO - 'USER': 'cltbld', 18:25:05 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 18:25:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:05 INFO - '_': '/tools/buildbot/bin/python'} 18:25:05 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/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-072', '--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 18:25:06 INFO - mozversion application_buildid: 20170829003906 18:25:06 INFO - mozversion application_changeset: 1b4c59eef820b46eb0037aca68f83a15088db45f 18:25:06 INFO - mozversion application_display_name: Nightly 18:25:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:25:06 INFO - mozversion application_name: Firefox 18:25:06 INFO - mozversion application_remotingname: firefox 18:25:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:25:06 INFO - mozversion application_vendor: Mozilla 18:25:06 INFO - mozversion application_version: 57.0a1 18:25:06 INFO - mozversion platform_buildid: 20170829003906 18:25:06 INFO - mozversion platform_changeset: 1b4c59eef820b46eb0037aca68f83a15088db45f 18:25:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:25:06 INFO - mozversion platform_version: 57.0a1 18:25:06 INFO - using testdate: 1503969906 18:25:06 INFO - actual date: 1503969906 18:25:06 INFO - starting webserver on 'localhost:51662' 18:25:06 INFO - SUITE-START | Running 2 tests 18:25:06 INFO - TEST-START | dromaeo_css 18:25:06 INFO - Initialising browser for dromaeo_css test... 18:25:06 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51662/getInfo.html -profile /tmp/tmpNMbOhs/profile 18:25:06 INFO - TEST-INFO | started process 6950 (/builds/slave/test/build/application/firefox/firefox http://localhost:51662/getInfo.html) 18:25:13 INFO - TEST-INFO | 6950: exit 0 18:25:13 INFO - Browser initialized. 18:25:13 INFO - Running cycle 1/1 for dromaeo_css test... 18:25:13 INFO - Using env: {'DISPLAY': ':0', 18:25:13 INFO - 'HOME': '/home/cltbld', 18:25:13 INFO - 'JSGC_DISABLE_POISONING': '1', 18:25:13 INFO - 'LANG': 'en_US.UTF-8', 18:25:13 INFO - 'LANGUAGE': 'en_US:en', 18:25:13 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:25:13 INFO - 'LOGNAME': 'cltbld', 18:25:13 INFO - 'MAIL': '/var/mail/cltbld', 18:25:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:25:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:25:13 INFO - 'MOZ_ACCELERATED': '1', 18:25:13 INFO - 'MOZ_CRASHREPORTER': '1', 18:25:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:13 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:25:13 INFO - 'MOZ_NO_REMOTE': '1', 18:25:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:25:13 INFO - 'MOZ_WEBRENDER': '1', 18:25:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:13 INFO - 'NO_EM_RESTART': '1', 18:25:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:13 INFO - 'PWD': '/builds/slave/test', 18:25:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:25:13 INFO - 'RUST_BACKTRACE': 'full', 18:25:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:25:13 INFO - 'SHELL': '/bin/bash', 18:25:13 INFO - 'SHLVL': '1', 18:25:13 INFO - 'TERM': 'linux', 18:25:13 INFO - 'TMOUT': '86400', 18:25:13 INFO - 'USER': 'cltbld', 18:25:13 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 18:25:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:13 INFO - '_': '/tools/buildbot/bin/python'} 18:25:13 INFO - TEST-INFO | started process 7133 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNMbOhs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:25:14 INFO - PID 7133 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:25:14 INFO - PID 7133 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:25:14 INFO - PID 7133 | 18:25:14 INFO - PID 7133 | (/builds/slave/test/build/application/firefox/firefox:7193): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:25:14 INFO - PID 7133 | 18:25:25 INFO - PID 7133 | RSS: Main: 198688768 18:25:25 INFO - PID 7133 | 18:25:29 INFO - PID 7133 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:28:28 INFO - PID 7133 | Cycle 1(1): loaded http://localhost:51662/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51662/tests/dromaeo/cssquery-ext.html) 18:28:29 INFO - PID 7133 | RSS: Main: 211943424 18:28:29 INFO - PID 7133 | 18:31:32 INFO - PID 7133 | Cycle 1(1): loaded http://localhost:51662/tests/dromaeo/cssquery-ext.html (next: http://localhost:51662/tests/dromaeo/cssquery-jquery.html) 18:31:32 INFO - PID 7133 | RSS: Main: 206422016 18:31:32 INFO - PID 7133 | 18:34:34 INFO - PID 7133 | Cycle 1(1): loaded http://localhost:51662/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51662/tests/dromaeo/cssquery-mootools.html) 18:34:35 INFO - PID 7133 | RSS: Main: 211619840 18:34:35 INFO - PID 7133 | 18:37:37 INFO - PID 7133 | Cycle 1(1): loaded http://localhost:51662/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51662/tests/dromaeo/cssquery-prototype.html) 18:37:38 INFO - PID 7133 | RSS: Main: 210485248 18:37:38 INFO - PID 7133 | 18:40:42 INFO - PID 7133 | Cycle 1(1): loaded http://localhost:51662/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51662/tests/dromaeo/cssquery-yui.html) 18:40:42 INFO - PID 7133 | RSS: Main: 211734528 18:40:42 INFO - PID 7133 | 18:43:44 INFO - PID 7133 | Cycle 1(1): loaded http://localhost:51662/tests/dromaeo/cssquery-yui.html (next: http://localhost:51662/tests/dromaeo/cssquery-dojo.html) 18:43:45 INFO - PID 7133 | RSS: Main: 217882624 18:43:45 INFO - PID 7133 | 18:43:45 INFO - PID 7133 | __start_tp_report 18:43:45 INFO - PID 7133 | _x_x_mozilla_page_load 18:43:45 INFO - PID 7133 | _x_x_mozilla_page_load_details 18:43:45 INFO - PID 7133 | |i|pagename|runs| 18:43:45 INFO - PID 7133 | |0;dojo.html;3263.4615288477403;3303.405387030334;3305.157184917845;3330.51707502412;3371.1403592084016;6134.294556126047;6202.78290259841;6264.5928014679075;6273;6314.747410103596;6150.569460137792;6184.84537886552;6207.9689601552045;6257.4994000479965;6267.37326267373;5697.6296540724925;5717.571182161355;5725.685087320186;5731.770729170828;5745.770169193227;5686.488216060554;5704.914426283609;5718.08510638298;5724.370319264877;5725.198472213893;5719.113537401709;5720.427957204288;5734.5125664318375;5753.942460575404;5759.539236861041;5645.830625081254;5672.68800215988;5722.62802917809;5729.369769325392;5746.511546518551;5610.66336019836;5635.520980716644;5672.546196304327;5717.828465146053;5772.855678608026;5960.821175364703;6056.757729690807;6112.358202388729;6116.816495505142;6122.632642041492;6418.133551970471;6438.356164383524;6442.581232219891;6486;6488.124103246096;5877.23595932526;6480.3195664455025;6492.383223593751;6516.902246466307;6736.191657001143;6128.509719222451;6238.812835614939;6240.781572645026;6251.218597675291;6309.842253943596;6759.053732477457;6810.727570897108;6812.795616131524;6816.7614133505085;6826.385625293748;6501.024846273002;6506.251781045145;6509.739610415625;6548.443382312462;6646.634435106019;6592.80221593353;6677.532572719863;6681.866362672719;6703.4972377070935;6741.561798483083;6651.700673469705;6673.733050678015;6729.4279986200745;6756.662166891636;6775.4918381121615;6503;6551.312112228243;6609.768658096944;6693.832654183684;6707.0610114584;4956.405231372259;4981.526754958273;5006.599472042228;5009.048280826704;5010.749462526932;4311.202427550913;4335.609795118455;4357.346398040319;4365.214261432972;4399;5837.737301821428;5842.678652674143;5921.407859214044;5923.851903702442;5924.6149000314845;6517.250516190604;6723.731050758012;6741.224759152662;6811.591304521745;6825.215100263434;5787.88424231513;5803.216565763652;5806.274215723035;5811.012127938261;5830.242068531149;5640.576956728261;5649.265595472644;5706.172604972256;5709.514691251207;5734.426557344233;6339;6434.453071488789;6537.640429776408;6545.312742162005;6552.6723663815665;6322.272938612118;6555.6066636002;6680.198376194888;6702.329767023259;6785.491088168308;6450.645214513247;6489.870202595827;6572.868542629216;6599.868002640016;6610.173728283965;6428.19647544057;6543.411093001652;6569.080328753883;6605.405513503808;6699.464042876656;6718.294579069127;6726.966365168143;6732.562383445061;6750.729970801113;6770.255271920183;6447.451966582706;6499.415052645285;6537.248216455168;6538.117354157128;6546.116274302908;6435.163428754232;6449.7097630605795;6513.772017979538;6529.902051469327;6548.214214294315;6311.084892690443;6386.393292637192;6461.773837915651;6486.513511486563;6544.8363790905605;6341.55609107358;6367.140436041075;6484.805455836333;6516.837079073061;6636.867262654817;6349.619022858643;6393.041043843461;6397.96801015992;6402.679866006588;6405.295417504164 18:43:45 INFO - PID 7133 | |1;ext.html;826.5040975414752;865.5282870835395;887.3256325192856;893.7140115163146;1116.759896622226;35874.28251434973;39607.801960990226;39875.20249595006;40138.79930600343;40141.59858401415;39290.60709392905;39648.20703585927;39790.801045994805;39840.60159398405;39913.20173596526;7579.621018949058;7646.464747467666;7754;7754.0695116586085;7771.572563509005;7602;7643.885341719879;7691.538507689556;7693.538387696728;7744.186860379664;7768.756998880181;7820.335271501901;7823.0612326520895;7830.530168189898;7835.647395867171;7675.654595543242;7729.34300584451;7759.72840950564;7763.456558040939;7765.844683106363;7640.044994375703;7644.120926093515;7698.538087714755;7703.499272547322;7720.3051725344985;35809.82095089534;35969.46045809315;36063.27873442516;36079.27841443157;36128.27743445116;3304.058343372127;3340;3342.665733426662;3345.46472564391;3346.380919529895;29364.8531757342;29431.411371772658;29493.705062949524;29495;29502.852485737647;4179.1432756284885;4238.046439551107;4241.491021077491;4258.957410425887;4284.485861696585;180388;180399;181446;181796;181913;44402;44405.11189776251;44423.777881110385;44727.32909006368;44748.552514475086;48887.26669099966;49099.509004909494;49457.25814112791;49683.503164967886;50031.74984125056;12509.249445033327;14634.707305854035;14800.92599537017;14880.255987200597;14890.329935152944;12809.039322050883;13028;13058.281794501394;13060.804087938688;13092.214467132002;13447.932760336136;13467.461301548024;13634;13635.931820340835;13708.86291137076;5534.75093620788;5552.52803511706;5562.137868630369;5662.603617746719;5664.734106357408;983.896690847465;985.6008316631776;991.2218908157206;1010.8635334229932;1012.6050840172338;21249.893750531148;21765.238216662656;21995.670064949038;22294.77705222927;22506.099756009902;1074.5272080284649;1089.7057794395469;1098.994081956695;1100.9174311926638;1115.4255558387438;11557.76884462306;11557.942210288895;11681.88318116825;11714.70713232176;11876.228045177037;232908;233008;234561;234799;235077;47182.528174717816;47601.76199119051;47662.523374765806;47789.28316075192;48267;1332.3737843213673;1388.2850332078988;1397.084909384355;1401.7757158854533;1424.7221791750717;21532.13871445125;21562.353129406143;26118.73881261163;26771.598426024015;26793.33016674599;45759.771201145115;45916.54083459123;46168.30747538722;46284.768576158254;46314.53685463102;209193;209536;211320;211919;212234;29435.411291773617;29885.252868677588;32167.51748723724;32212.35575288528;33364.66635333616;39598.40602391024;39739.602603974745;40089.59910400859;40477;40738;30098.24754381158;30404.69595304107;30672.693273066983;30674.846625766728;31109.68890311068;29760;29942.25144371408;30117.548236776904;30249.848750756104;30548.08355749331;33752;33782.15544611404;33987;34080.829595851865;34146;28524.144275671762;28691;29115.7088429113;29253.41493170167;29857.25356866096;22110.557788844453;22153.89230538499;22503.437414064778;22611.660825087307;22911.65632515547 18:43:45 INFO - PID 7133 | |2;jquery.html;557.5845994733924;557.946995035472;559.2450192240476;560.5095541401273;575.177496180462;21378.251761188363;21989.340319790394;22126.22558210463;22157.335279941588;22157.66763498549;22753.203637872677;24563.14029008985;24588.754112458915;24621;24671.25986220412;6429.678516074189;6576.0793488911595;6585.374389432996;6588.275289718127;6595.274519802818;6264.966280563702;6440.581362211465;6522.7390904363765;6566.737330506774;6574.309697481767;6521.543491955565;6530.2816690164045;6534.836629084263;6537.280899101095;6541.28045914949;6518.967405162944;6557.377049180344;6587;6587.901181482282;6589.769358072468;6080.817575472743;6087.29996050451;6087.908681369783;6091;6097.390260973867;27909.720902790916;28931.855340723163;29403;29491.557626635404;29638.851805741047;1914.9234030638759;1957.314939771083;1959.6962470817045;1961.3625571689258;1962.8331591814713;18443.72334414985;18952.81047189524;19140.23439062436;19159.23363065476;19236.519087022938;2330.347502699247;2332.3819187915215;2421.83047186697;2426.7694569015916;2430.3316587938457;131194;132706;133948.33025834808;133995.33002335022;134129;13512.932435337956;13866.306684665726;14070.859291406954;14352.354144063542;14758.778618320734;20227.59544809125;22366.88165591714;22466.663000055014;22511.549769004527;22716.545669086525;8862.689805856764;9081.182693557612;9442.38624489406;9668.178204852526;9768.511574421393;8597.828043439096;8649.956750216208;8666.6100025499;8721.215090641872;8795.340349473816;7615.5149745799035;8979.326550508738;9056.230220431336;9078.727638170865;9153.954230228806;2619.4106326076862;2635.8418494890366;2655.362712948917;2698.487287415385;2735.7948153888537;934.4393363981557;966.7003228999033;967.3567077893122;973.0852998181687;974.6832279509188;18906.149223284985;19193.80806191948;19707;19726;19935.10292036862;999.6351331763854;1006.2050979726081;1017.2218253036285;1022.621629996891;1024.4212020208558;10775.78448431027;10858.945705271422;10863.67408977732;10897.400642964712;10902.72743181411;450705;452663;468226;469374;469460;110051.44974275237;111349.44325278484;111678.44160779307;115492;115748;1185.4132204558625;1213.9271643701406;1252.2236213547842;1253.7304479536942;1254.203580726227;20350.694739578623;20482.180712771322;20496.59006819885;20545.383638490868;20722.896385517975;54189;55311.17033244585;55412;56021.43978560162;56950;69277.65361173161;70379;70966.93549596863;71118;71451.64274178595;11866.584669536871;12450.315232662291;13697.178169309873;14108.78836817428;14295.642608934444;22143.667844982017;22339.218127366115;22444.102235911036;22446.32661020172;22511.887440562692;18081.09594520295;18088.186031628873;18205.817941820413;18246;18430.539236519195;16543.91728041352;16781;16819.831801681827;17012.23444945005;17274.65450690954;18623.255069797055;18657.160427780513;18728.25086996505;18926;19020.904895475433;13900.930495347458;13919.79120313176;13991;14025.929870350583;14182.645433864236;16073.276702548179;16991.49025529236;17092.40176593813;18119.27522899122;18388.080595970416 18:43:45 INFO - PID 7133 | |3;mootools.html;1265.6329664397322;1335.8263425754656;1337.190999445335;1356.7625665508538;1364.6861221918964;14235.501757438491;14813.111213327207;14822;14846.960712750099;14855.182964936925;14059.367328470218;15466.072035677838;15484.148371839572;15499.302531386087;15515.224238788043;2624.1209194919725;2625.5536558785006;2654.4558365535017;2656.309359566517;2670.5727083666616;2433.3916520869784;2553.9744602553924;2607.282997175773;2609.973900260992;2629.1718108795762;2627.684677838662;2635.3938594123365;2652.4960257551;2655.7344265573483;2663.9334016649545;2522.672052633155;2553.770160685547;2580.9676129548143;2584.3022383956322;2584.5347837389263;2521.369657585604;2525.2802951158833;2539.3270783242456;2545.2491515003117;2548.604966230237;14218.075825071337;14355.138691678429;14383.640408989755;14410.92794536031;14551.78172327416;7441.25587441257;7606.6196690165825;7664.923350766477;7672.923270767276;7676;5210.739463026834;5341.706206158659;5373.704446255454;5435.646682965634;5536.806211782608;1764.6382491589193;1784.8929064256185;1822.2528763207022;2369.4431808524982;2421.721502027272;23572.410689732893;23936.40158996039;23972.760272397227;23981.640275395883;24023.7597624025;6281.654509002049;6294.9685251574365;6381.298057213703;6485.805425837232;6489.5781774184525;15918.522444326689;15936.840631593535;15939.442119525993;15944.601384965237;15952.12263325505;7501.2123727007875;7609.543427394374;7615.504992175491;7620.809479762939;7662.46362754613;6881.931180688229;7006.054182685374;7053.259407762111;7053.717851285994;7104.863221884474;4692.671512994058;4839.975800120977;4844.346013288232;4859.854204373874;4861.562459378602;1260.3761138236448;1289.2264641215384;1322.8280323558067;1336.1114858619103;1381.053978025056;902.9683961061331;909.9363044586815;912.4707669551644;932.2681694869494;944.3059351376733;8064.55644939534;8356.70751523706;8368.163183681583;8376.288015518627;8424.705135320235;974.4299584743005;981.2444417798256;987.3976874106789;992.2607657295217;997.2221667099674;4488.573585509372;4508.887277818015;4699.154152252559;5142.125838607446;5734.713264336767;20180.798192018185;20196.596068078557;20494.79505204929;20610.79389206119;20969.89515052415;19654.312099076462;19864.304749333704;20020.099095540736;20050.09774560119;20063.899680501505;10965.945170274257;11035.39305338214;11135.443227838576;11331.886681133083;11354.48904799286;8028.558429286445;8447.535385553854;8612.78468038304;8646.308295336232;8742.694005709769;16808.159592020595;17490.47528574145;17562.82437175612;17649.38227162043;17662.735058974384;17437.30250790005;17857.553561161076;17884.463466096036;18140.365087221882;18256;11115.277506962346;11299.887001129884;11357.716057098309;11361.261518001304;11400.600978965696;12905.677358066123;13163.407646655733;13186.472541098632;13196.538121165713;13220.537281195113;10572.41851698164;10596.629117980834;10752;10777.676669699922;10784.19118566095;10008;10115.241356898116;10135.290529662852;10259;10368.066873981075;9451.338406311492;9514.809703805746;9515.524223788922;9518.333716640045;9544.713658589975;7562.34915526958;7620.8475830484185;7640.235976402315;7656.73201437947;7689.50018248812;7439.739609113655;7463;7514.586697731677;7534.924650753642;7583.696652133853 18:43:45 INFO - PID 7133 | |4;prototype.html;493.80106734043943;501.19569549621355;517.9689218646881;518.2563022063337;519.1848797388101;6120.418560236776;6163.1987841580585;6249;6271.498280137578;6297.149884765561;5917.792877249297;6028;6049.795392202421;6103.603265787732;6160.753569857212;4897.314375987365;4902.779374928136;4947.381577302837;4992.87517812054;5007.674501157431;4894.167991441445;4959.181735013718;5007.574356179729;5015.924761128567;5045.21799121135;4951.405831300249;4982.80412700951;5002.624803139761;5017.19724844025;5035.62232832539;4936.456989731127;4971.353724015873;4982.850514484571;4991.525805048533;5006.224035274502;4440.755758433284;4466.922880860383;4481.170983368087;4487.304467807495;4494.4606647202545;6095.847603809896;6109.908351374733;6132.172156758825;6168.8766224675255;6177.2587289525545;5677.971610141964;5717.313922329306;5717.484866510378;5719.942800572025;5735.283089613798;6475.676216189172;6478.838029049264;6490.1178658189065;6540.672966351711;6554.901676474857;4751.40607424072;4767.1419144553965;4833.782479788383;4848.345473361086;4860.708357498562;6813.454923606149;6843.3840954314355;6896.482763792686;6901.413379862718;6996.160460744743;4936.950630493721;4979.128652485873;4989.451160372356;5004.499550044967;5004.674696144739;4943.01139772047;4990.151674215392;5006.774695138727;5019.974900125549;5055.244891534049;5566.638168519034;5601.411851755588;5612.354579223442;5648.181013752983;5684.488396044376;6520.771772987922;6556.18047744032;6577.769778057745;6633.635150066792;6650.900236496456;1638.262781748218;1664.8501634852921;1679.9832001679827;1698.431025606411;1750.9387171449193;1196.6589521986193;1196.9581064662696;1205.4515195585986;1211.19455562052;1222.205566381859;5734.311882574035;5749.655020698771;5775.191473193756;5780.88438231233;5862.765489380462;6524.575902566318;6536.182977127859;6550.8362290943105;6562.80311590653;6573.408393244631;5655.688937108416;5666.433356664301;5691.26013618227;5718.914216286759;5736.885262294731;3490.2321489272317;3510.437855154483;3511.8928642814617;3547.9822600887346;3589.6948759355223;6362;6397.6801159941815;6453.483721302285;6490.448311893448;6504.1869766279215;6605.966970165118;6708.463322934251;6735.0805020568905;6735.562188457735;6787.287334829871;6387.968060159762;6446.580972236789;6466.741330346827;6519.272392815908;6555.639439830855;6506.544541882023;6632.369924857131;6635.469162466895;6653.600783952785;6711.765088221889;6445.677716114269;6628.469722422098;6647.4682025438815;6651.933480665131;6672.466202703869;6340.524460665376;6400.871982560229;6464.450521705708;6500.674966251763;6519.608823470607;4934.284528743384;4952.380952380952;4952.4304704959395;4958.925616115689;4960.082384758831;6713.885126185236;6719.932800671931;6746.807287631637;6768.763093291711;6799.660016999229;6458.773942911976;6478.222613286436;6479.676016199265;6484.28672845996;6503.642299673564;6178.66017369049;6212.596181248386;6317.241930968129;6496.512761542808;6520.771772987922;5841.349156777014;6323.36766323364;6496.336502034143;6504.577202481823;6534.379233972765;6470.6441145737435;6494.64279464634;6549.9520076789395;6623.735050597922;6625.4037136657935;6233.594816336924;6266.937330626635;6309.779157729457;6311.779087731908;6384.3824032146595 18:43:45 INFO - PID 7133 | |5;yui.html;851.4593233296855;857.8970523537176;868.3834477520962;886.6719313853876;892.3753372639151;19237.134328955195;19415.22339106434;19513.219471221153;19525.21899124037;19560.804391956077;19680.21279148836;21209.469763255925;21382.89308553455;21491.892540537276;21608.8919555402;907.1790030022839;923.8521836506127;930.2186163622556;930.5207817973749;931.6599441203946;857.3741168946673;936.091990768953;938.3244064273712;942.6794889737488;946.2997381937352;983.6458874805064;984.0405604535556;988.0415996483399;988.3871999360371;994.6518718448558;975.9902400976006;986.8717066781344;987.2250283527433;989.0850962859327;989.3173710140017;725.6480606905646;728.8688036153491;730.12749764032;730.8501757139827;731.4550659758461;19289.80710192908;19706;19739.901300493548;19798;19987.700184497244;21123.5775284495;21189.89405052965;21345.573088538298;21647.783522164733;21748.238811641517;16157;16309.83690163095;16472.341106355732;16685.332586696517;16687.165641717864;3709.0356507308024;3762.6613604775703;3767.717421193394;3773;3774.8112594370173;123277;124112.37943810313;124242;124956.37521812423;127285.54500592528;16216.918915405347;16978;17159.571010724583;17160.141992900306;17220.138993050547;29327.413451730845;30766.692333076382;31075.844620776752;31340.373192536477;31454.685453145175;8960.05919378469;9078.54607269634;9135.862962055575;9151.588178531982;9171.495567743705;4226.788660567021;4295.935560966588;4297.290946993773;4301.290287102655;4304.440422745086;4610.4467463903875;5491.313585801775;5693.373728889819;5775.075987841925;5785.595008349375;875.4265955798835;1096.9451527423757;1143.3254379915888;1145.3070892110154;1147.386148410613;667.5694177255643;678.4775722693498;689.4553302890761;702.666233539075;705.2136867392923;11311.264767790068;11847.940760296144;11906.940465297617;12185.32980686053;12457.128001040022;674.7975607317883;677.376813331736;679.8572299817093;681.5774219983642;694.6214313199217;5771.365149833515;5860.2381690380835;5922.289325280994;5959.255093113361;6016.037434010625;113590.43204783923;114008.71982560468;115830;118147.40926295485;119493.40253298677;98171;99309.50345248227;99641.50179249057;100984.49507752561;104831;83315.5834220825;83505.58247208725;84632;87459;88323;11059.50232239535;11774.05807535378;11959.162858599815;12030.63908082759;12234.510619575118;39948;40256.59743402529;40455.79772101121;40581.39127913143;40732;69838;70419.64790176017;71403.64298178475;72649.63675181591;72691;17946.461606151835;18771.061446927873;19408;19646.607067858276;19651.80348196557;18722.6255474887;18908.43274701761;19754.802451975298;21936.341909742732;21941.122355105617;17602.911985440503;17668.646627067643;17865.642687146443;19104.140313686195;19165.904170479058;19098.331558395388;19197.90401047986;19528.609427811647;19580.412587622395;19672.704909426084;18139.00235487061;18365.449036528924;18525.53686157857;18630.254789808256;19291.228350865807;12844.42200100979;13452.798208026692;13605.931970340085;13648.93175534116;13744.65638359009;13665.385057672627;13683.726325473235;13834.169949803045;13971.231582262666;14176.787348189991 18:43:45 INFO - PID 7133 | __end_tp_report 18:43:45 INFO - PID 7133 | __start_cc_report 18:43:45 INFO - PID 7133 | _x_x_mozilla_cycle_collect,386 18:43:45 INFO - PID 7133 | __end_cc_report 18:43:45 INFO - PID 7133 | __startTimestamp1503971025010__endTimestamp 18:43:45 INFO - PID 7133 | 18:43:45 INFO - PID 7133 | ------- Summary: start ------- 18:43:45 INFO - PID 7133 | Number of tests: 6 18:43:45 INFO - PID 7133 | 18:43:45 INFO - PID 7133 | [#0] dojo.html Cycles:165 Average:6108.24 Median:6386.39 stddev:730.91 (11.4%) stddev-sans-first:698.25 18:43:45 INFO - PID 7133 | Values: 3263.5 3303.4 3305.2 3330.5 3371.1 6134.3 6202.8 6264.6 6273.0 6314.7 6150.6 6184.8 6208.0 6257.5 6267.4 5697.6 5717.6 5725.7 5731.8 5745.8 5686.5 5704.9 5718.1 5724.4 5725.2 5719.1 5720.4 5734.5 5753.9 5759.5 5645.8 5672.7 5722.6 5729.4 5746.5 5610.7 5635.5 5672.5 5717.8 5772.9 5960.8 6056.8 6112.4 6116.8 6122.6 6418.1 6438.4 6442.6 6486.0 6488.1 5877.2 6480.3 6492.4 6516.9 6736.2 6128.5 6238.8 6240.8 6251.2 6309.8 6759.1 6810.7 6812.8 6816.8 6826.4 6501.0 6506.3 6509.7 6548.4 6646.6 6592.8 6677.5 6681.9 6703.5 6741.6 6651.7 6673.7 6729.4 6756.7 6775.5 6503.0 6551.3 6609.8 6693.8 6707.1 4956.4 4981.5 5006.6 5009.0 5010.7 4311.2 4335.6 4357.3 4365.2 4399.0 5837.7 5842.7 5921.4 5923.9 5924.6 6517.3 6723.7 6741.2 6811.6 6825.2 5787.9 5803.2 5806.3 5811.0 5830.2 5640.6 5649.3 5706.2 5709.5 5734.4 6339.0 6434.5 6537.6 6545.3 6552.7 6322.3 6555.6 6680.2 6702.3 6785.5 6450.6 6489.9 6572.9 6599.9 6610.2 6428.2 6543.4 6569.1 6605.4 6699.5 6718.3 6727.0 6732.6 6750.7 6770.3 6447.5 6499.4 6537.2 6538.1 6546.1 6435.2 6449.7 6513.8 6529.9 6548.2 6311.1 6386.4 6461.8 6486.5 6544.8 6341.6 6367.1 6484.8 6516.8 6636.9 6349.6 6393.0 6398.0 6402.7 6405.3 18:43:45 INFO - PID 7133 | 18:43:45 INFO - PID 7133 | [#1] ext.html Cycles:180 Average:36978.26 Median:3344.07 stddev:54426.48 (1627.6%) stddev-sans-first:54511.47 18:43:45 INFO - PID 7133 | Values: 826.5 865.5 887.3 893.7 1116.8 35874.3 39607.8 39875.2 40138.8 40141.6 39290.6 39648.2 39790.8 39840.6 39913.2 7579.6 7646.5 7754.0 7754.1 7771.6 7602.0 7643.9 7691.5 7693.5 7744.2 7768.8 7820.3 7823.1 7830.5 7835.6 7675.7 7729.3 7759.7 7763.5 7765.8 7640.0 7644.1 7698.5 7703.5 7720.3 35809.8 35969.5 36063.3 36079.3 36128.3 3304.1 3340.0 3342.7 3345.5 3346.4 29364.9 29431.4 29493.7 29495.0 29502.9 4179.1 4238.0 4241.5 4259.0 4284.5 180388.0 180399.0 181446.0 181796.0 181913.0 44402.0 44405.1 44423.8 44727.3 44748.6 48887.3 49099.5 49457.3 49683.5 50031.7 12509.2 14634.7 14800.9 14880.3 14890.3 12809.0 13028.0 13058.3 13060.8 13092.2 13447.9 13467.5 13634.0 13635.9 13708.9 5534.8 5552.5 5562.1 5662.6 5664.7 983.9 985.6 991.2 1010.9 1012.6 21249.9 21765.2 21995.7 22294.8 22506.1 1074.5 1089.7 1099.0 1100.9 1115.4 11557.8 11557.9 11681.9 11714.7 11876.2 232908.0 233008.0 234561.0 234799.0 235077.0 47182.5 47601.8 47662.5 47789.3 48267.0 1332.4 1388.3 1397.1 1401.8 1424.7 21532.1 21562.4 26118.7 26771.6 26793.3 45759.8 45916.5 46168.3 46284.8 46314.5 209193.0 209536.0 211320.0 211919.0 212234.0 29435.4 29885.3 32167.5 32212.4 33364.7 39598.4 39739.6 40089.6 40477.0 40738.0 30098.2 30404.7 30672.7 30674.8 31109.7 29760.0 29942.3 30117.5 30249.8 30548.1 33752.0 33782.2 33987.0 34080.8 34146.0 28524.1 28691.0 29115.7 29253.4 29857.3 22110.6 22153.9 22503.4 22611.7 22911.7 18:43:45 INFO - PID 7133 | 18:43:45 INFO - PID 7133 | [#2] jquery.html Cycles:180 Average:33510.47 Median:22489.11 stddev:78076.05 (347.2%) stddev-sans-first:78255.87 18:43:45 INFO - PID 7133 | Values: 557.6 557.9 559.2 560.5 575.2 21378.3 21989.3 22126.2 22157.3 22157.7 22753.2 24563.1 24588.8 24621.0 24671.3 6429.7 6576.1 6585.4 6588.3 6595.3 6265.0 6440.6 6522.7 6566.7 6574.3 6521.5 6530.3 6534.8 6537.3 6541.3 6519.0 6557.4 6587.0 6587.9 6589.8 6080.8 6087.3 6087.9 6091.0 6097.4 27909.7 28931.9 29403.0 29491.6 29638.9 1914.9 1957.3 1959.7 1961.4 1962.8 18443.7 18952.8 19140.2 19159.2 19236.5 2330.3 2332.4 2421.8 2426.8 2430.3 131194.0 132706.0 133948.3 133995.3 134129.0 13512.9 13866.3 14070.9 14352.4 14758.8 20227.6 22366.9 22466.7 22511.5 22716.5 8862.7 9081.2 9442.4 9668.2 9768.5 8597.8 8650.0 8666.6 8721.2 8795.3 7615.5 8979.3 9056.2 9078.7 9154.0 2619.4 2635.8 2655.4 2698.5 2735.8 934.4 966.7 967.4 973.1 974.7 18906.1 19193.8 19707.0 19726.0 19935.1 999.6 1006.2 1017.2 1022.6 1024.4 10775.8 10858.9 10863.7 10897.4 10902.7 450705.0 452663.0 468226.0 469374.0 469460.0 110051.4 111349.4 111678.4 115492.0 115748.0 1185.4 1213.9 1252.2 1253.7 1254.2 20350.7 20482.2 20496.6 20545.4 20722.9 54189.0 55311.2 55412.0 56021.4 56950.0 69277.7 70379.0 70966.9 71118.0 71451.6 11866.6 12450.3 13697.2 14108.8 14295.6 22143.7 22339.2 22444.1 22446.3 22511.9 18081.1 18088.2 18205.8 18246.0 18430.5 16543.9 16781.0 16819.8 17012.2 17274.7 18623.3 18657.2 18728.3 18926.0 19020.9 13900.9 13919.8 13991.0 14025.9 14182.6 16073.3 16991.5 17092.4 18119.3 18388.1 18:43:45 INFO - PID 7133 | 18:43:45 INFO - PID 7133 | [#3] mootools.html Cycles:180 Average:8920.53 Median:2546.93 stddev:6192.57 (243.1%) stddev-sans-first:6183.23 18:43:45 INFO - PID 7133 | Values: 1265.6 1335.8 1337.2 1356.8 1364.7 14235.5 14813.1 14822.0 14847.0 14855.2 14059.4 15466.1 15484.1 15499.3 15515.2 2624.1 2625.6 2654.5 2656.3 2670.6 2433.4 2554.0 2607.3 2610.0 2629.2 2627.7 2635.4 2652.5 2655.7 2663.9 2522.7 2553.8 2581.0 2584.3 2584.5 2521.4 2525.3 2539.3 2545.2 2548.6 14218.1 14355.1 14383.6 14410.9 14551.8 7441.3 7606.6 7664.9 7672.9 7676.0 5210.7 5341.7 5373.7 5435.6 5536.8 1764.6 1784.9 1822.3 2369.4 2421.7 23572.4 23936.4 23972.8 23981.6 24023.8 6281.7 6295.0 6381.3 6485.8 6489.6 15918.5 15936.8 15939.4 15944.6 15952.1 7501.2 7609.5 7615.5 7620.8 7662.5 6881.9 7006.1 7053.3 7053.7 7104.9 4692.7 4840.0 4844.3 4859.9 4861.6 1260.4 1289.2 1322.8 1336.1 1381.1 903.0 909.9 912.5 932.3 944.3 8064.6 8356.7 8368.2 8376.3 8424.7 974.4 981.2 987.4 992.3 997.2 4488.6 4508.9 4699.2 5142.1 5734.7 20180.8 20196.6 20494.8 20610.8 20969.9 19654.3 19864.3 20020.1 20050.1 20063.9 10965.9 11035.4 11135.4 11331.9 11354.5 8028.6 8447.5 8612.8 8646.3 8742.7 16808.2 17490.5 17562.8 17649.4 17662.7 17437.3 17857.6 17884.5 18140.4 18256.0 11115.3 11299.9 11357.7 11361.3 11400.6 12905.7 13163.4 13186.5 13196.5 13220.5 10572.4 10596.6 10752.0 10777.7 10784.2 10008.0 10115.2 10135.3 10259.0 10368.1 9451.3 9514.8 9515.5 9518.3 9544.7 7562.3 7620.8 7640.2 7656.7 7689.5 7439.7 7463.0 7514.6 7534.9 7583.7 18:43:45 INFO - PID 7133 | 18:43:45 INFO - PID 7133 | [#4] prototype.html Cycles:180 Average:5469.60 Median:6106.76 stddev:1535.07 (25.1%) stddev-sans-first:1493.25 18:43:45 INFO - PID 7133 | Values: 493.8 501.2 518.0 518.3 519.2 6120.4 6163.2 6249.0 6271.5 6297.1 5917.8 6028.0 6049.8 6103.6 6160.8 4897.3 4902.8 4947.4 4992.9 5007.7 4894.2 4959.2 5007.6 5015.9 5045.2 4951.4 4982.8 5002.6 5017.2 5035.6 4936.5 4971.4 4982.9 4991.5 5006.2 4440.8 4466.9 4481.2 4487.3 4494.5 6095.8 6109.9 6132.2 6168.9 6177.3 5678.0 5717.3 5717.5 5719.9 5735.3 6475.7 6478.8 6490.1 6540.7 6554.9 4751.4 4767.1 4833.8 4848.3 4860.7 6813.5 6843.4 6896.5 6901.4 6996.2 4937.0 4979.1 4989.5 5004.5 5004.7 4943.0 4990.2 5006.8 5020.0 5055.2 5566.6 5601.4 5612.4 5648.2 5684.5 6520.8 6556.2 6577.8 6633.6 6650.9 1638.3 1664.9 1680.0 1698.4 1750.9 1196.7 1197.0 1205.5 1211.2 1222.2 5734.3 5749.7 5775.2 5780.9 5862.8 6524.6 6536.2 6550.8 6562.8 6573.4 5655.7 5666.4 5691.3 5718.9 5736.9 3490.2 3510.4 3511.9 3548.0 3589.7 6362.0 6397.7 6453.5 6490.4 6504.2 6606.0 6708.5 6735.1 6735.6 6787.3 6388.0 6446.6 6466.7 6519.3 6555.6 6506.5 6632.4 6635.5 6653.6 6711.8 6445.7 6628.5 6647.5 6651.9 6672.5 6340.5 6400.9 6464.5 6500.7 6519.6 4934.3 4952.4 4952.4 4958.9 4960.1 6713.9 6719.9 6746.8 6768.8 6799.7 6458.8 6478.2 6479.7 6484.3 6503.6 6178.7 6212.6 6317.2 6496.5 6520.8 5841.3 6323.4 6496.3 6504.6 6534.4 6470.6 6494.6 6550.0 6623.7 6625.4 6233.6 6266.9 6309.8 6311.8 6384.4 18:43:45 INFO - PID 7133 | 18:43:45 INFO - PID 7133 | [#5] yui.html Cycles:180 Average:24104.41 Median:25634.27 stddev:32656.65 (127.4%) stddev-sans-first:32701.58 18:43:45 INFO - PID 7133 | Values: 851.5 857.9 868.4 886.7 892.4 19237.1 19415.2 19513.2 19525.2 19560.8 19680.2 21209.5 21382.9 21491.9 21608.9 907.2 923.9 930.2 930.5 931.7 857.4 936.1 938.3 942.7 946.3 983.6 984.0 988.0 988.4 994.7 976.0 986.9 987.2 989.1 989.3 725.6 728.9 730.1 730.9 731.5 19289.8 19706.0 19739.9 19798.0 19987.7 21123.6 21189.9 21345.6 21647.8 21748.2 16157.0 16309.8 16472.3 16685.3 16687.2 3709.0 3762.7 3767.7 3773.0 3774.8 123277.0 124112.4 124242.0 124956.4 127285.5 16216.9 16978.0 17159.6 17160.1 17220.1 29327.4 30766.7 31075.8 31340.4 31454.7 8960.1 9078.5 9135.9 9151.6 9171.5 4226.8 4295.9 4297.3 4301.3 4304.4 4610.4 5491.3 5693.4 5775.1 5785.6 875.4 1096.9 1143.3 1145.3 1147.4 667.6 678.5 689.5 702.7 705.2 11311.3 11847.9 11906.9 12185.3 12457.1 674.8 677.4 679.9 681.6 694.6 5771.4 5860.2 5922.3 5959.3 6016.0 113590.4 114008.7 115830.0 118147.4 119493.4 98171.0 99309.5 99641.5 100984.5 104831.0 83315.6 83505.6 84632.0 87459.0 88323.0 11059.5 11774.1 11959.2 12030.6 12234.5 39948.0 40256.6 40455.8 40581.4 40732.0 69838.0 70419.6 71403.6 72649.6 72691.0 17946.5 18771.1 19408.0 19646.6 19651.8 18722.6 18908.4 19754.8 21936.3 21941.1 17602.9 17668.6 17865.6 19104.1 19165.9 19098.3 19197.9 19528.6 19580.4 19672.7 18139.0 18365.4 18525.5 18630.3 19291.2 12844.4 13452.8 13605.9 13648.9 13744.7 13665.4 13683.7 13834.2 13971.2 14176.8 18:43:45 INFO - PID 7133 | -------- Summary: end -------- 18:43:45 INFO - PID 7133 | 18:43:46 INFO - TEST-INFO | 7133: exit 0 18:43:46 INFO - TEST-OK | dromaeo_css | took 1119882ms 18:43:46 INFO - TEST-START | kraken 18:43:46 INFO - Initialising browser for kraken test... 18:43:46 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51662/getInfo.html -profile /tmp/tmpiCxjb3/profile 18:43:46 INFO - TEST-INFO | started process 7513 (/builds/slave/test/build/application/firefox/firefox http://localhost:51662/getInfo.html) 18:43:52 INFO - TEST-INFO | 7513: exit 0 18:43:52 INFO - Browser initialized. 18:43:52 INFO - Running cycle 1/1 for kraken test... 18:43:52 INFO - Using env: {'DISPLAY': ':0', 18:43:52 INFO - 'HOME': '/home/cltbld', 18:43:52 INFO - 'JSGC_DISABLE_POISONING': '1', 18:43:52 INFO - 'LANG': 'en_US.UTF-8', 18:43:52 INFO - 'LANGUAGE': 'en_US:en', 18:43:52 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:43:52 INFO - 'LOGNAME': 'cltbld', 18:43:52 INFO - 'MAIL': '/var/mail/cltbld', 18:43:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:43:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:43:52 INFO - 'MOZ_ACCELERATED': '1', 18:43:52 INFO - 'MOZ_CRASHREPORTER': '1', 18:43:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:43:52 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:43:52 INFO - 'MOZ_NO_REMOTE': '1', 18:43:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:43:52 INFO - 'MOZ_WEBRENDER': '1', 18:43:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:43:52 INFO - 'NO_EM_RESTART': '1', 18:43:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:43:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:43:52 INFO - 'PWD': '/builds/slave/test', 18:43:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:43:52 INFO - 'RUST_BACKTRACE': 'full', 18:43:52 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:43:52 INFO - 'SHELL': '/bin/bash', 18:43:52 INFO - 'SHLVL': '1', 18:43:52 INFO - 'TERM': 'linux', 18:43:52 INFO - 'TMOUT': '86400', 18:43:52 INFO - 'USER': 'cltbld', 18:43:52 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440', 18:43:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:43:52 INFO - '_': '/tools/buildbot/bin/python'} 18:43:52 INFO - TEST-INFO | started process 7699 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiCxjb3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:43:53 INFO - PID 7699 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:43:53 INFO - PID 7699 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:43:53 INFO - PID 7699 | 18:43:53 INFO - PID 7699 | (/builds/slave/test/build/application/firefox/firefox:7760): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:43:53 INFO - PID 7699 | 18:44:04 INFO - PID 7699 | RSS: Main: 198389760 18:44:04 INFO - PID 7699 | 18:44:06 INFO - PID 7699 | Cycle 1(1): loaded http://localhost:51662/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51662/tests/kraken/driver.html?testName=audio-beat-detection) 18:44:07 INFO - PID 7699 | RSS: Main: 206016512 18:44:07 INFO - PID 7699 | 18:44:08 INFO - PID 7699 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:44:10 INFO - PID 7699 | Cycle 1(1): loaded http://localhost:51662/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51662/tests/kraken/driver.html?testName=audio-dft) 18:44:11 INFO - PID 7699 | RSS: Main: 209821696 18:44:11 INFO - PID 7699 | 18:44:14 INFO - PID 7699 | Cycle 1(1): loaded http://localhost:51662/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51662/tests/kraken/driver.html?testName=audio-fft) 18:44:14 INFO - PID 7699 | RSS: Main: 202940416 18:44:14 INFO - PID 7699 | 18:44:16 INFO - PID 7699 | Cycle 1(1): loaded http://localhost:51662/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51662/tests/kraken/driver.html?testName=audio-oscillator) 18:44:17 INFO - PID 7699 | RSS: Main: 204083200 18:44:17 INFO - PID 7699 | 18:44:19 INFO - PID 7699 | Cycle 1(1): loaded http://localhost:51662/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51662/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:44:20 INFO - PID 7699 | RSS: Main: 203145216 18:44:20 INFO - PID 7699 | 18:44:23 INFO - PID 7699 | Cycle 1(1): loaded http://localhost:51662/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51662/tests/kraken/driver.html?testName=imaging-darkroom) 18:44:24 INFO - PID 7699 | RSS: Main: 204525568 18:44:24 INFO - PID 7699 | 18:44:27 INFO - PID 7699 | Cycle 1(1): loaded http://localhost:51662/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51662/tests/kraken/driver.html?testName=imaging-desaturate) 18:44:28 INFO - PID 7699 | RSS: Main: 204578816 18:44:28 INFO - PID 7699 | 18:44:31 INFO - PID 7699 | Cycle 1(1): loaded http://localhost:51662/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51662/tests/kraken/driver.html?testName=json-parse-financial) 18:44:31 INFO - PID 7699 | RSS: Main: 205709312 18:44:31 INFO - PID 7699 | 18:44:33 INFO - PID 7699 | Cycle 1(1): loaded http://localhost:51662/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51662/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:44:34 INFO - PID 7699 | RSS: Main: 205524992 18:44:34 INFO - PID 7699 | 18:44:35 INFO - PID 7699 | Cycle 1(1): loaded http://localhost:51662/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51662/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:44:36 INFO - PID 7699 | RSS: Main: 205152256 18:44:36 INFO - PID 7699 | 18:44:38 INFO - PID 7699 | Cycle 1(1): loaded http://localhost:51662/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51662/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:44:39 INFO - PID 7699 | RSS: Main: 204693504 18:44:39 INFO - PID 7699 | 18:44:42 INFO - PID 7699 | Cycle 1(1): loaded http://localhost:51662/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51662/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:44:42 INFO - PID 7699 | RSS: Main: 205660160 18:44:42 INFO - PID 7699 | 18:44:45 INFO - PID 7699 | Cycle 1(1): loaded http://localhost:51662/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51662/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:44:46 INFO - PID 7699 | RSS: Main: 205762560 18:44:46 INFO - PID 7699 | 18:44:48 INFO - PID 7699 | Cycle 1(1): loaded http://localhost:51662/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51662/tests/kraken/driver.html?testName=ai-astar) 18:44:48 INFO - PID 7699 | RSS: Main: 207699968 18:44:48 INFO - PID 7699 | 18:44:48 INFO - PID 7699 | __start_tp_report 18:44:48 INFO - PID 7699 | _x_x_mozilla_page_load 18:44:48 INFO - PID 7699 | _x_x_mozilla_page_load_details 18:44:48 INFO - PID 7699 | |i|pagename|runs| 18:44:48 INFO - PID 7699 | |0;ai-astar;98;116;99;118;112;116;108;99;107;110 18:44:48 INFO - PID 7699 | |1;audio-beat-detection;150;120;148;172;122;161;145;119;171;118 18:44:48 INFO - PID 7699 | |2;audio-dft;170;161;163;169;172;163;164;174;167;163 18:44:48 INFO - PID 7699 | |3;audio-fft;75;73;74;183;74;81;74;88;160;83 18:44:48 INFO - PID 7699 | |4;audio-oscillator;81;81;130;80;81;82;131;84;79;146 18:44:48 INFO - PID 7699 | |5;imaging-gaussian-blur;114;115;119;120;114;114;117;114;116;121 18:44:48 INFO - PID 7699 | |6;imaging-darkroom;156;150;152;147;148;147;148;150;149;153 18:44:48 INFO - PID 7699 | |7;imaging-desaturate;99;94;97;94;98;94;100;94;99;98 18:44:48 INFO - PID 7699 | |8;json-parse-financial;53;58;55;53;61;57;54;52;52;52 18:44:48 INFO - PID 7699 | |9;json-stringify-tinderbox;38;50;50;38;38;49;39;39;49;38 18:44:48 INFO - PID 7699 | |10;stanford-crypto-aes;82;76;75;75;77;78;81;79;84;82 18:44:48 INFO - PID 7699 | |11;stanford-crypto-ccm;135;118;132;119;131;125;127;117;126;156 18:44:48 INFO - PID 7699 | |12;stanford-crypto-pbkdf2;174;180;169;178;179;171;174;171;171;168 18:44:48 INFO - PID 7699 | |13;stanford-crypto-sha256-iterative;66;64;72;66;71;65;64;63;65;67 18:44:48 INFO - PID 7699 | __end_tp_report 18:44:48 INFO - PID 7699 | __start_cc_report 18:44:48 INFO - PID 7699 | _x_x_mozilla_cycle_collect,940 18:44:48 INFO - PID 7699 | __end_cc_report 18:44:48 INFO - PID 7699 | __startTimestamp1503971088476__endTimestamp 18:44:48 INFO - PID 7699 | 18:44:48 INFO - PID 7699 | ------- Summary: start ------- 18:44:48 INFO - PID 7699 | Number of tests: 14 18:44:48 INFO - PID 7699 | 18:44:48 INFO - PID 7699 | [#0] ai-astar Cycles:10 Average:108.30 Median:117.00 stddev:7.53 (6.4%) stddev-sans-first:7.00 18:44:48 INFO - PID 7699 | Values: 98.0 116.0 99.0 118.0 112.0 116.0 108.0 99.0 107.0 110.0 18:44:48 INFO - PID 7699 | 18:44:48 INFO - PID 7699 | [#1] audio-beat-detection Cycles:10 Average:142.60 Median:149.00 stddev:21.59 (14.5%) stddev-sans-first:22.74 18:44:48 INFO - PID 7699 | Values: 150.0 120.0 148.0 172.0 122.0 161.0 145.0 119.0 171.0 118.0 18:44:48 INFO - PID 7699 | 18:44:48 INFO - PID 7699 | [#2] audio-dft Cycles:10 Average:166.60 Median:168.00 stddev:4.45 (2.7%) stddev-sans-first:4.55 18:44:48 INFO - PID 7699 | Values: 170.0 161.0 163.0 169.0 172.0 163.0 164.0 174.0 167.0 163.0 18:44:48 INFO - PID 7699 | 18:44:48 INFO - PID 7699 | [#3] audio-fft Cycles:10 Average:96.50 Median:74.50 stddev:40.20 (54.0%) stddev-sans-first:41.88 18:44:48 INFO - PID 7699 | Values: 75.0 73.0 74.0 183.0 74.0 81.0 74.0 88.0 160.0 83.0 18:44:48 INFO - PID 7699 | 18:44:48 INFO - PID 7699 | [#4] audio-oscillator Cycles:10 Average:97.50 Median:81.00 stddev:26.71 (33.0%) stddev-sans-first:27.65 18:44:48 INFO - PID 7699 | Values: 81.0 81.0 130.0 80.0 81.0 82.0 131.0 84.0 79.0 146.0 18:44:48 INFO - PID 7699 | 18:44:48 INFO - PID 7699 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:2.72 (2.3%) stddev-sans-first:2.74 18:44:48 INFO - PID 7699 | Values: 114.0 115.0 119.0 120.0 114.0 114.0 117.0 114.0 116.0 121.0 18:44:48 INFO - PID 7699 | 18:44:48 INFO - PID 7699 | [#6] imaging-darkroom Cycles:10 Average:150.00 Median:150.00 stddev:2.91 (1.9%) stddev-sans-first:2.12 18:44:48 INFO - PID 7699 | Values: 156.0 150.0 152.0 147.0 148.0 147.0 148.0 150.0 149.0 153.0 18:44:48 INFO - PID 7699 | 18:44:48 INFO - PID 7699 | [#7] imaging-desaturate Cycles:10 Average:96.70 Median:97.50 stddev:2.45 (2.5%) stddev-sans-first:2.46 18:44:48 INFO - PID 7699 | Values: 99.0 94.0 97.0 94.0 98.0 94.0 100.0 94.0 99.0 98.0 18:44:48 INFO - PID 7699 | 18:44:48 INFO - PID 7699 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:54.50 stddev:3.06 (5.6%) stddev-sans-first:3.18 18:44:48 INFO - PID 7699 | Values: 53.0 58.0 55.0 53.0 61.0 57.0 54.0 52.0 52.0 52.0 18:44:48 INFO - PID 7699 | 18:44:48 INFO - PID 7699 | [#9] json-stringify-tinderbox Cycles:10 Average:42.80 Median:44.00 stddev:5.79 (13.2%) stddev-sans-first:5.87 18:44:48 INFO - PID 7699 | Values: 38.0 50.0 50.0 38.0 38.0 49.0 39.0 39.0 49.0 38.0 18:44:48 INFO - PID 7699 | 18:44:48 INFO - PID 7699 | [#10] stanford-crypto-aes Cycles:10 Average:78.90 Median:80.00 stddev:3.21 (4.0%) stddev-sans-first:3.21 18:44:48 INFO - PID 7699 | Values: 82.0 76.0 75.0 75.0 77.0 78.0 81.0 79.0 84.0 82.0 18:44:48 INFO - PID 7699 | 18:44:48 INFO - PID 7699 | [#11] stanford-crypto-ccm Cycles:10 Average:128.60 Median:129.00 stddev:11.40 (8.8%) stddev-sans-first:11.86 18:44:48 INFO - PID 7699 | Values: 135.0 118.0 132.0 119.0 131.0 125.0 127.0 117.0 126.0 156.0 18:44:48 INFO - PID 7699 | 18:44:48 INFO - PID 7699 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.50 Median:174.00 stddev:4.25 (2.4%) stddev-sans-first:4.50 18:44:48 INFO - PID 7699 | Values: 174.0 180.0 169.0 178.0 179.0 171.0 174.0 171.0 171.0 168.0 18:44:48 INFO - PID 7699 | 18:44:48 INFO - PID 7699 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.00 stddev:2.98 (4.5%) stddev-sans-first:3.16 18:44:48 INFO - PID 7699 | Values: 66.0 64.0 72.0 66.0 71.0 65.0 64.0 63.0 65.0 67.0 18:44:48 INFO - PID 7699 | -------- Summary: end -------- 18:44:48 INFO - PID 7699 | 18:44:49 INFO - PID 7699 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:44:49 INFO - TEST-INFO | 7699: exit 0 18:44:49 INFO - TEST-OK | kraken | took 63498ms 18:44:49 INFO - SUITE-END | took 1183s 18:44:49 INFO - Completed test suite (00:19:43) 18:44:49 INFO - javascript benchmark 18:44:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8395.590163409131, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3263.4615288477403, 3303.405387030334, 3305.157184917845, 3330.51707502412, 3371.1403592084016, 6134.294556126047, 6202.78290259841, 6264.5928014679075, 6273.0, 6314.747410103596, 6150.569460137792, 6184.84537886552, 6207.9689601552045, 6257.4994000479965, 6267.37326267373, 5697.6296540724925, 5717.571182161355, 5725.685087320186, 5731.770729170828, 5745.770169193227, 5686.488216060554, 5704.914426283609, 5718.08510638298, 5724.370319264877, 5725.198472213893, 5719.113537401709, 5720.427957204288, 5734.5125664318375, 5753.942460575404, 5759.539236861041, 5645.830625081254, 5672.68800215988, 5722.62802917809, 5729.369769325392, 5746.511546518551, 5610.66336019836, 5635.520980716644, 5672.546196304327, 5717.828465146053, 5772.855678608026, 5960.821175364703, 6056.757729690807, 6112.358202388729, 6116.816495505142, 6122.632642041492, 6418.133551970471, 6438.356164383524, 6442.581232219891, 6486.0, 6488.124103246096, 5877.23595932526, 6480.3195664455025, 6492.383223593751, 6516.902246466307, 6736.191657001143, 6128.509719222451, 6238.812835614939, 6240.781572645026, 6251.218597675291, 6309.842253943596, 6759.053732477457, 6810.727570897108, 6812.795616131524, 6816.7614133505085, 6826.385625293748, 6501.024846273002, 6506.251781045145, 6509.739610415625, 6548.443382312462, 6646.634435106019, 6592.80221593353, 6677.532572719863, 6681.866362672719, 6703.4972377070935, 6741.561798483083, 6651.700673469705, 6673.733050678015, 6729.4279986200745, 6756.662166891636, 6775.4918381121615, 6503.0, 6551.312112228243, 6609.768658096944, 6693.832654183684, 6707.0610114584, 4956.405231372259, 4981.526754958273, 5006.599472042228, 5009.048280826704, 5010.749462526932, 4311.202427550913, 4335.609795118455, 4357.346398040319, 4365.214261432972, 4399.0, 5837.737301821428, 5842.678652674143, 5921.407859214044, 5923.851903702442, 5924.6149000314845, 6517.250516190604, 6723.731050758012, 6741.224759152662, 6811.591304521745, 6825.215100263434, 5787.88424231513, 5803.216565763652, 5806.274215723035, 5811.012127938261, 5830.242068531149, 5640.576956728261, 5649.265595472644, 5706.172604972256, 5709.514691251207, 5734.426557344233, 6339.0, 6434.453071488789, 6537.640429776408, 6545.312742162005, 6552.6723663815665, 6322.272938612118, 6555.6066636002, 6680.198376194888, 6702.329767023259, 6785.491088168308, 6450.645214513247, 6489.870202595827, 6572.868542629216, 6599.868002640016, 6610.173728283965, 6428.19647544057, 6543.411093001652, 6569.080328753883, 6605.405513503808, 6699.464042876656, 6718.294579069127, 6726.966365168143, 6732.562383445061, 6750.729970801113, 6770.255271920183, 6447.451966582706, 6499.415052645285, 6537.248216455168, 6538.117354157128, 6546.116274302908, 6435.163428754232, 6449.7097630605795, 6513.772017979538, 6529.902051469327, 6548.214214294315, 6311.084892690443, 6386.393292637192, 6461.773837915651, 6486.513511486563, 6544.8363790905605, 6341.55609107358, 6367.140436041075, 6484.805455836333, 6516.837079073061, 6636.867262654817, 6349.619022858643, 6393.041043843461, 6397.96801015992, 6402.679866006588, 6405.295417504164], "value": 6054.494680453627, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [826.5040975414752, 865.5282870835395, 887.3256325192856, 893.7140115163146, 1116.759896622226, 35874.28251434973, 39607.801960990226, 39875.20249595006, 40138.79930600343, 40141.59858401415, 39290.60709392905, 39648.20703585927, 39790.801045994805, 39840.60159398405, 39913.20173596526, 7579.621018949058, 7646.464747467666, 7754.0, 7754.0695116586085, 7771.572563509005, 7602.0, 7643.885341719879, 7691.538507689556, 7693.538387696728, 7744.186860379664, 7768.756998880181, 7820.335271501901, 7823.0612326520895, 7830.530168189898, 7835.647395867171, 7675.654595543242, 7729.34300584451, 7759.72840950564, 7763.456558040939, 7765.844683106363, 7640.044994375703, 7644.120926093515, 7698.538087714755, 7703.499272547322, 7720.3051725344985, 35809.82095089534, 35969.46045809315, 36063.27873442516, 36079.27841443157, 36128.27743445116, 3304.058343372127, 3340.0, 3342.665733426662, 3345.46472564391, 3346.380919529895, 29364.8531757342, 29431.411371772658, 29493.705062949524, 29495.0, 29502.852485737647, 4179.1432756284885, 4238.046439551107, 4241.491021077491, 4258.957410425887, 4284.485861696585, 180388.0, 180399.0, 181446.0, 181796.0, 181913.0, 44402.0, 44405.11189776251, 44423.777881110385, 44727.32909006368, 44748.552514475086, 48887.26669099966, 49099.509004909494, 49457.25814112791, 49683.503164967886, 50031.74984125056, 12509.249445033327, 14634.707305854035, 14800.92599537017, 14880.255987200597, 14890.329935152944, 12809.039322050883, 13028.0, 13058.281794501394, 13060.804087938688, 13092.214467132002, 13447.932760336136, 13467.461301548024, 13634.0, 13635.931820340835, 13708.86291137076, 5534.75093620788, 5552.52803511706, 5562.137868630369, 5662.603617746719, 5664.734106357408, 983.896690847465, 985.6008316631776, 991.2218908157206, 1010.8635334229932, 1012.6050840172338, 21249.893750531148, 21765.238216662656, 21995.670064949038, 22294.77705222927, 22506.099756009902, 1074.5272080284649, 1089.7057794395469, 1098.994081956695, 1100.9174311926638, 1115.4255558387438, 11557.76884462306, 11557.942210288895, 11681.88318116825, 11714.70713232176, 11876.228045177037, 232908.0, 233008.0, 234561.0, 234799.0, 235077.0, 47182.528174717816, 47601.76199119051, 47662.523374765806, 47789.28316075192, 48267.0, 1332.3737843213673, 1388.2850332078988, 1397.084909384355, 1401.7757158854533, 1424.7221791750717, 21532.13871445125, 21562.353129406143, 26118.73881261163, 26771.598426024015, 26793.33016674599, 45759.771201145115, 45916.54083459123, 46168.30747538722, 46284.768576158254, 46314.53685463102, 209193.0, 209536.0, 211320.0, 211919.0, 212234.0, 29435.411291773617, 29885.252868677588, 32167.51748723724, 32212.35575288528, 33364.66635333616, 39598.40602391024, 39739.602603974745, 40089.59910400859, 40477.0, 40738.0, 30098.24754381158, 30404.69595304107, 30672.693273066983, 30674.846625766728, 31109.68890311068, 29760.0, 29942.25144371408, 30117.548236776904, 30249.848750756104, 30548.08355749331, 33752.0, 33782.15544611404, 33987.0, 34080.829595851865, 34146.0, 28524.144275671762, 28691.0, 29115.7088429113, 29253.41493170167, 29857.25356866096, 22110.557788844453, 22153.89230538499, 22503.437414064778, 22611.660825087307, 22911.65632515547], "value": 16627.522890974884, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [557.5845994733924, 557.946995035472, 559.2450192240476, 560.5095541401273, 575.177496180462, 21378.251761188363, 21989.340319790394, 22126.22558210463, 22157.335279941588, 22157.66763498549, 22753.203637872677, 24563.14029008985, 24588.754112458915, 24621.0, 24671.25986220412, 6429.678516074189, 6576.0793488911595, 6585.374389432996, 6588.275289718127, 6595.274519802818, 6264.966280563702, 6440.581362211465, 6522.7390904363765, 6566.737330506774, 6574.309697481767, 6521.543491955565, 6530.2816690164045, 6534.836629084263, 6537.280899101095, 6541.28045914949, 6518.967405162944, 6557.377049180344, 6587.0, 6587.901181482282, 6589.769358072468, 6080.817575472743, 6087.29996050451, 6087.908681369783, 6091.0, 6097.390260973867, 27909.720902790916, 28931.855340723163, 29403.0, 29491.557626635404, 29638.851805741047, 1914.9234030638759, 1957.314939771083, 1959.6962470817045, 1961.3625571689258, 1962.8331591814713, 18443.72334414985, 18952.81047189524, 19140.23439062436, 19159.23363065476, 19236.519087022938, 2330.347502699247, 2332.3819187915215, 2421.83047186697, 2426.7694569015916, 2430.3316587938457, 131194.0, 132706.0, 133948.33025834808, 133995.33002335022, 134129.0, 13512.932435337956, 13866.306684665726, 14070.859291406954, 14352.354144063542, 14758.778618320734, 20227.59544809125, 22366.88165591714, 22466.663000055014, 22511.549769004527, 22716.545669086525, 8862.689805856764, 9081.182693557612, 9442.38624489406, 9668.178204852526, 9768.511574421393, 8597.828043439096, 8649.956750216208, 8666.6100025499, 8721.215090641872, 8795.340349473816, 7615.5149745799035, 8979.326550508738, 9056.230220431336, 9078.727638170865, 9153.954230228806, 2619.4106326076862, 2635.8418494890366, 2655.362712948917, 2698.487287415385, 2735.7948153888537, 934.4393363981557, 966.7003228999033, 967.3567077893122, 973.0852998181687, 974.6832279509188, 18906.149223284985, 19193.80806191948, 19707.0, 19726.0, 19935.10292036862, 999.6351331763854, 1006.2050979726081, 1017.2218253036285, 1022.621629996891, 1024.4212020208558, 10775.78448431027, 10858.945705271422, 10863.67408977732, 10897.400642964712, 10902.72743181411, 450705.0, 452663.0, 468226.0, 469374.0, 469460.0, 110051.44974275237, 111349.44325278484, 111678.44160779307, 115492.0, 115748.0, 1185.4132204558625, 1213.9271643701406, 1252.2236213547842, 1253.7304479536942, 1254.203580726227, 20350.694739578623, 20482.180712771322, 20496.59006819885, 20545.383638490868, 20722.896385517975, 54189.0, 55311.17033244585, 55412.0, 56021.43978560162, 56950.0, 69277.65361173161, 70379.0, 70966.93549596863, 71118.0, 71451.64274178595, 11866.584669536871, 12450.315232662291, 13697.178169309873, 14108.78836817428, 14295.642608934444, 22143.667844982017, 22339.218127366115, 22444.102235911036, 22446.32661020172, 22511.887440562692, 18081.09594520295, 18088.186031628873, 18205.817941820413, 18246.0, 18430.539236519195, 16543.91728041352, 16781.0, 16819.831801681827, 17012.23444945005, 17274.65450690954, 18623.255069797055, 18657.160427780513, 18728.25086996505, 18926.0, 19020.904895475433, 13900.930495347458, 13919.79120313176, 13991.0, 14025.929870350583, 14182.645433864236, 16073.276702548179, 16991.49025529236, 17092.40176593813, 18119.27522899122, 18388.080595970416], "value": 11719.313058905493, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1265.6329664397322, 1335.8263425754656, 1337.190999445335, 1356.7625665508538, 1364.6861221918964, 14235.501757438491, 14813.111213327207, 14822.0, 14846.960712750099, 14855.182964936925, 14059.367328470218, 15466.072035677838, 15484.148371839572, 15499.302531386087, 15515.224238788043, 2624.1209194919725, 2625.5536558785006, 2654.4558365535017, 2656.309359566517, 2670.5727083666616, 2433.3916520869784, 2553.9744602553924, 2607.282997175773, 2609.973900260992, 2629.1718108795762, 2627.684677838662, 2635.3938594123365, 2652.4960257551, 2655.7344265573483, 2663.9334016649545, 2522.672052633155, 2553.770160685547, 2580.9676129548143, 2584.3022383956322, 2584.5347837389263, 2521.369657585604, 2525.2802951158833, 2539.3270783242456, 2545.2491515003117, 2548.604966230237, 14218.075825071337, 14355.138691678429, 14383.640408989755, 14410.92794536031, 14551.78172327416, 7441.25587441257, 7606.6196690165825, 7664.923350766477, 7672.923270767276, 7676.0, 5210.739463026834, 5341.706206158659, 5373.704446255454, 5435.646682965634, 5536.806211782608, 1764.6382491589193, 1784.8929064256185, 1822.2528763207022, 2369.4431808524982, 2421.721502027272, 23572.410689732893, 23936.40158996039, 23972.760272397227, 23981.640275395883, 24023.7597624025, 6281.654509002049, 6294.9685251574365, 6381.298057213703, 6485.805425837232, 6489.5781774184525, 15918.522444326689, 15936.840631593535, 15939.442119525993, 15944.601384965237, 15952.12263325505, 7501.2123727007875, 7609.543427394374, 7615.504992175491, 7620.809479762939, 7662.46362754613, 6881.931180688229, 7006.054182685374, 7053.259407762111, 7053.717851285994, 7104.863221884474, 4692.671512994058, 4839.975800120977, 4844.346013288232, 4859.854204373874, 4861.562459378602, 1260.3761138236448, 1289.2264641215384, 1322.8280323558067, 1336.1114858619103, 1381.053978025056, 902.9683961061331, 909.9363044586815, 912.4707669551644, 932.2681694869494, 944.3059351376733, 8064.55644939534, 8356.70751523706, 8368.163183681583, 8376.288015518627, 8424.705135320235, 974.4299584743005, 981.2444417798256, 987.3976874106789, 992.2607657295217, 997.2221667099674, 4488.573585509372, 4508.887277818015, 4699.154152252559, 5142.125838607446, 5734.713264336767, 20180.798192018185, 20196.596068078557, 20494.79505204929, 20610.79389206119, 20969.89515052415, 19654.312099076462, 19864.304749333704, 20020.099095540736, 20050.09774560119, 20063.899680501505, 10965.945170274257, 11035.39305338214, 11135.443227838576, 11331.886681133083, 11354.48904799286, 8028.558429286445, 8447.535385553854, 8612.78468038304, 8646.308295336232, 8742.694005709769, 16808.159592020595, 17490.47528574145, 17562.82437175612, 17649.38227162043, 17662.735058974384, 17437.30250790005, 17857.553561161076, 17884.463466096036, 18140.365087221882, 18256.0, 11115.277506962346, 11299.887001129884, 11357.716057098309, 11361.261518001304, 11400.600978965696, 12905.677358066123, 13163.407646655733, 13186.472541098632, 13196.538121165713, 13220.537281195113, 10572.41851698164, 10596.629117980834, 10752.0, 10777.676669699922, 10784.19118566095, 10008.0, 10115.241356898116, 10135.290529662852, 10259.0, 10368.066873981075, 9451.338406311492, 9514.809703805746, 9515.524223788922, 9518.333716640045, 9544.713658589975, 7562.34915526958, 7620.8475830484185, 7640.235976402315, 7656.73201437947, 7689.50018248812, 7439.739609113655, 7463.0, 7514.586697731677, 7534.924650753642, 7583.696652133853], "value": 6446.401461609637, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.80106734043943, 501.19569549621355, 517.9689218646881, 518.2563022063337, 519.1848797388101, 6120.418560236776, 6163.1987841580585, 6249.0, 6271.498280137578, 6297.149884765561, 5917.792877249297, 6028.0, 6049.795392202421, 6103.603265787732, 6160.753569857212, 4897.314375987365, 4902.779374928136, 4947.381577302837, 4992.87517812054, 5007.674501157431, 4894.167991441445, 4959.181735013718, 5007.574356179729, 5015.924761128567, 5045.21799121135, 4951.405831300249, 4982.80412700951, 5002.624803139761, 5017.19724844025, 5035.62232832539, 4936.456989731127, 4971.353724015873, 4982.850514484571, 4991.525805048533, 5006.224035274502, 4440.755758433284, 4466.922880860383, 4481.170983368087, 4487.304467807495, 4494.4606647202545, 6095.847603809896, 6109.908351374733, 6132.172156758825, 6168.8766224675255, 6177.2587289525545, 5677.971610141964, 5717.313922329306, 5717.484866510378, 5719.942800572025, 5735.283089613798, 6475.676216189172, 6478.838029049264, 6490.1178658189065, 6540.672966351711, 6554.901676474857, 4751.40607424072, 4767.1419144553965, 4833.782479788383, 4848.345473361086, 4860.708357498562, 6813.454923606149, 6843.3840954314355, 6896.482763792686, 6901.413379862718, 6996.160460744743, 4936.950630493721, 4979.128652485873, 4989.451160372356, 5004.499550044967, 5004.674696144739, 4943.01139772047, 4990.151674215392, 5006.774695138727, 5019.974900125549, 5055.244891534049, 5566.638168519034, 5601.411851755588, 5612.354579223442, 5648.181013752983, 5684.488396044376, 6520.771772987922, 6556.18047744032, 6577.769778057745, 6633.635150066792, 6650.900236496456, 1638.262781748218, 1664.8501634852921, 1679.9832001679827, 1698.431025606411, 1750.9387171449193, 1196.6589521986193, 1196.9581064662696, 1205.4515195585986, 1211.19455562052, 1222.205566381859, 5734.311882574035, 5749.655020698771, 5775.191473193756, 5780.88438231233, 5862.765489380462, 6524.575902566318, 6536.182977127859, 6550.8362290943105, 6562.80311590653, 6573.408393244631, 5655.688937108416, 5666.433356664301, 5691.26013618227, 5718.914216286759, 5736.885262294731, 3490.2321489272317, 3510.437855154483, 3511.8928642814617, 3547.9822600887346, 3589.6948759355223, 6362.0, 6397.6801159941815, 6453.483721302285, 6490.448311893448, 6504.1869766279215, 6605.966970165118, 6708.463322934251, 6735.0805020568905, 6735.562188457735, 6787.287334829871, 6387.968060159762, 6446.580972236789, 6466.741330346827, 6519.272392815908, 6555.639439830855, 6506.544541882023, 6632.369924857131, 6635.469162466895, 6653.600783952785, 6711.765088221889, 6445.677716114269, 6628.469722422098, 6647.4682025438815, 6651.933480665131, 6672.466202703869, 6340.524460665376, 6400.871982560229, 6464.450521705708, 6500.674966251763, 6519.608823470607, 4934.284528743384, 4952.380952380952, 4952.4304704959395, 4958.925616115689, 4960.082384758831, 6713.885126185236, 6719.932800671931, 6746.807287631637, 6768.763093291711, 6799.660016999229, 6458.773942911976, 6478.222613286436, 6479.676016199265, 6484.28672845996, 6503.642299673564, 6178.66017369049, 6212.596181248386, 6317.241930968129, 6496.512761542808, 6520.771772987922, 5841.349156777014, 6323.36766323364, 6496.336502034143, 6504.577202481823, 6534.379233972765, 6470.6441145737435, 6494.64279464634, 6549.9520076789395, 6623.735050597922, 6625.4037136657935, 6233.594816336924, 6266.937330626635, 6309.779157729457, 6311.779087731908, 6384.3824032146595], "value": 5015.452544050033, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [851.4593233296855, 857.8970523537176, 868.3834477520962, 886.6719313853876, 892.3753372639151, 19237.134328955195, 19415.22339106434, 19513.219471221153, 19525.21899124037, 19560.804391956077, 19680.21279148836, 21209.469763255925, 21382.89308553455, 21491.892540537276, 21608.8919555402, 907.1790030022839, 923.8521836506127, 930.2186163622556, 930.5207817973749, 931.6599441203946, 857.3741168946673, 936.091990768953, 938.3244064273712, 942.6794889737488, 946.2997381937352, 983.6458874805064, 984.0405604535556, 988.0415996483399, 988.3871999360371, 994.6518718448558, 975.9902400976006, 986.8717066781344, 987.2250283527433, 989.0850962859327, 989.3173710140017, 725.6480606905646, 728.8688036153491, 730.12749764032, 730.8501757139827, 731.4550659758461, 19289.80710192908, 19706.0, 19739.901300493548, 19798.0, 19987.700184497244, 21123.5775284495, 21189.89405052965, 21345.573088538298, 21647.783522164733, 21748.238811641517, 16157.0, 16309.83690163095, 16472.341106355732, 16685.332586696517, 16687.165641717864, 3709.0356507308024, 3762.6613604775703, 3767.717421193394, 3773.0, 3774.8112594370173, 123277.0, 124112.37943810313, 124242.0, 124956.37521812423, 127285.54500592528, 16216.918915405347, 16978.0, 17159.571010724583, 17160.141992900306, 17220.138993050547, 29327.413451730845, 30766.692333076382, 31075.844620776752, 31340.373192536477, 31454.685453145175, 8960.05919378469, 9078.54607269634, 9135.862962055575, 9151.588178531982, 9171.495567743705, 4226.788660567021, 4295.935560966588, 4297.290946993773, 4301.290287102655, 4304.440422745086, 4610.4467463903875, 5491.313585801775, 5693.373728889819, 5775.075987841925, 5785.595008349375, 875.4265955798835, 1096.9451527423757, 1143.3254379915888, 1145.3070892110154, 1147.386148410613, 667.5694177255643, 678.4775722693498, 689.4553302890761, 702.666233539075, 705.2136867392923, 11311.264767790068, 11847.940760296144, 11906.940465297617, 12185.32980686053, 12457.128001040022, 674.7975607317883, 677.376813331736, 679.8572299817093, 681.5774219983642, 694.6214313199217, 5771.365149833515, 5860.2381690380835, 5922.289325280994, 5959.255093113361, 6016.037434010625, 113590.43204783923, 114008.71982560468, 115830.0, 118147.40926295485, 119493.40253298677, 98171.0, 99309.50345248227, 99641.50179249057, 100984.49507752561, 104831.0, 83315.5834220825, 83505.58247208725, 84632.0, 87459.0, 88323.0, 11059.50232239535, 11774.05807535378, 11959.162858599815, 12030.63908082759, 12234.510619575118, 39948.0, 40256.59743402529, 40455.79772101121, 40581.39127913143, 40732.0, 69838.0, 70419.64790176017, 71403.64298178475, 72649.63675181591, 72691.0, 17946.461606151835, 18771.061446927873, 19408.0, 19646.607067858276, 19651.80348196557, 18722.6255474887, 18908.43274701761, 19754.802451975298, 21936.341909742732, 21941.122355105617, 17602.911985440503, 17668.646627067643, 17865.642687146443, 19104.140313686195, 19165.904170479058, 19098.331558395388, 19197.90401047986, 19528.609427811647, 19580.412587622395, 19672.704909426084, 18139.00235487061, 18365.449036528924, 18525.53686157857, 18630.254789808256, 19291.228350865807, 12844.42200100979, 13452.798208026692, 13605.931970340085, 13648.93175534116, 13744.65638359009, 13665.385057672627, 13683.726325473235, 13834.169949803045, 13971.231582262666, 14176.787348189991], "value": 9180.080636477329, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1519.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 116.0, 99.0, 118.0, 112.0, 116.0, 108.0, 99.0, 107.0, 110.0], "value": 108.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 120.0, 148.0, 172.0, 122.0, 161.0, 145.0, 119.0, 171.0, 118.0], "value": 142.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 161.0, 163.0, 169.0, 172.0, 163.0, 164.0, 174.0, 167.0, 163.0], "value": 166.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 74.0, 183.0, 74.0, 81.0, 74.0, 88.0, 160.0, 83.0], "value": 96.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 130.0, 80.0, 81.0, 82.0, 131.0, 84.0, 79.0, 146.0], "value": 97.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 119.0, 120.0, 114.0, 114.0, 117.0, 114.0, 116.0, 121.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 150.0, 152.0, 147.0, 148.0, 147.0, 148.0, 150.0, 149.0, 153.0], "value": 150.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 94.0, 97.0, 94.0, 98.0, 94.0, 100.0, 94.0, 99.0, 98.0], "value": 96.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 58.0, 55.0, 53.0, 61.0, 57.0, 54.0, 52.0, 52.0, 52.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 50.0, 50.0, 38.0, 38.0, 49.0, 39.0, 39.0, 49.0, 38.0], "value": 42.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 76.0, 75.0, 75.0, 77.0, 78.0, 81.0, 79.0, 84.0, 82.0], "value": 78.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 118.0, 132.0, 119.0, 131.0, 125.0, 127.0, 117.0, 126.0, 156.0], "value": 128.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 180.0, 169.0, 178.0, 179.0, 171.0, 174.0, 171.0, 171.0, 168.0], "value": 173.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 72.0, 66.0, 71.0, 65.0, 64.0, 63.0, 65.0, 67.0], "value": 66.3, "unit": "score"}]}]} 18:44:49 INFO - Return code: 0 18:44:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:44:49 INFO - # TBPL SUCCESS # 18:44:49 INFO - Running post-action listener: _package_coverage_data 18:44:49 INFO - Running post-action listener: _resource_record_post_action 18:44:49 INFO - [mozharness: 2017-08-29 01:44:49.763556Z] Finished run-tests step (success) 18:44:49 INFO - Running post-run listener: _resource_record_post_run 18:44:49 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 6168576; Write bytes: 336220160; Read time: 3392; Write time: 213696 18:44:49 INFO - TinderboxPrint: CPU usage
13.2% 18:44:49 INFO - TinderboxPrint: I/O read bytes / time
6,168,576 / 3,392 18:44:49 INFO - TinderboxPrint: I/O write bytes / time
336,220,160 / 213,696 18:44:49 INFO - TinderboxPrint: CPU idle
8,302.2 (86.7%) 18:44:49 INFO - TinderboxPrint: CPU user
1,206.2 (12.6%) 18:44:49 INFO - TinderboxPrint: Swap in / out
0 / 0 18:44:49 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 14643200; Read time: 0; Write time: 2260 18:44:49 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:44:49 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 6164480; Write bytes: 320454656; Read time: 3376; Write time: 211408 18:44:50 INFO - Running post-run listener: _upload_blobber_files 18:44:50 INFO - Blob upload gear active. 18:44:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:44:50 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:44:50 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'] 18:44:50 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 18:44:50 INFO - (blobuploader) - INFO - Open directory for files ... 18:44:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:44:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:44:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:54 INFO - (blobuploader) - INFO - Done attempting. 18:44:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:44:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:44:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:55 INFO - (blobuploader) - INFO - Done attempting. 18:44:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:44:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:44:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:56 INFO - (blobuploader) - INFO - Done attempting. 18:44:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:44:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:44:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:57 INFO - (blobuploader) - INFO - Done attempting. 18:44:57 INFO - (blobuploader) - INFO - Iteration through files over. 18:44:57 INFO - Return code: 0 18:44:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:44:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:44:57 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b88535e428e100d4726650450729545368fe86626795a9171e74643d4c252d33713004ce3f1e77b799edea4d764ab2f7689c8fc0d4a2a78bf318bde82e521f7a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0614215b54e66bd946b8d9ac559796fc9d8a9e65e761a9740c3cbd118fd3a2939749f10f478150f0a9cebb1d71da8f80bf14f4abb2b0b0ff9f9987b6f07bb72c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/137e192d4d2c4c02bbf37e34203603120bc23699936d0aa012615593c21848cd8c1cd886700aad59ef02d1fb8b633670de9462687f774f58275afb93ce0e8026", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4796e10ea62bdb5a1e55573f14bc7c72971b0264cd629b79f451a069bcf12550ed472a15f7c155ae26f09a7342f96ff3997e209faaa740e20463f4329c85ef1"} 18:44:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:44:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:44:57 INFO - Contents: 18:44:57 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b88535e428e100d4726650450729545368fe86626795a9171e74643d4c252d33713004ce3f1e77b799edea4d764ab2f7689c8fc0d4a2a78bf318bde82e521f7a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0614215b54e66bd946b8d9ac559796fc9d8a9e65e761a9740c3cbd118fd3a2939749f10f478150f0a9cebb1d71da8f80bf14f4abb2b0b0ff9f9987b6f07bb72c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/137e192d4d2c4c02bbf37e34203603120bc23699936d0aa012615593c21848cd8c1cd886700aad59ef02d1fb8b633670de9462687f774f58275afb93ce0e8026", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4796e10ea62bdb5a1e55573f14bc7c72971b0264cd629b79f451a069bcf12550ed472a15f7c155ae26f09a7342f96ff3997e209faaa740e20463f4329c85ef1"} 18:44:57 INFO - Running post-run listener: copy_logs_to_upload_dir 18:44:57 INFO - Copying logs to upload dir... 18:44:57 INFO - mkdir: /builds/slave/test/build/upload/logs 18:44:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1291.727147 ========= master_lag: 0.19 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 31 secs) (at 2017-08-28 18:44:57.415211) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-28 18:44:57.419533) ========= 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=199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440 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/b88535e428e100d4726650450729545368fe86626795a9171e74643d4c252d33713004ce3f1e77b799edea4d764ab2f7689c8fc0d4a2a78bf318bde82e521f7a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0614215b54e66bd946b8d9ac559796fc9d8a9e65e761a9740c3cbd118fd3a2939749f10f478150f0a9cebb1d71da8f80bf14f4abb2b0b0ff9f9987b6f07bb72c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/137e192d4d2c4c02bbf37e34203603120bc23699936d0aa012615593c21848cd8c1cd886700aad59ef02d1fb8b633670de9462687f774f58275afb93ce0e8026", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4796e10ea62bdb5a1e55573f14bc7c72971b0264cd629b79f451a069bcf12550ed472a15f7c155ae26f09a7342f96ff3997e209faaa740e20463f4329c85ef1"} build_url:https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011229 build_url: 'https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b88535e428e100d4726650450729545368fe86626795a9171e74643d4c252d33713004ce3f1e77b799edea4d764ab2f7689c8fc0d4a2a78bf318bde82e521f7a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0614215b54e66bd946b8d9ac559796fc9d8a9e65e761a9740c3cbd118fd3a2939749f10f478150f0a9cebb1d71da8f80bf14f4abb2b0b0ff9f9987b6f07bb72c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/137e192d4d2c4c02bbf37e34203603120bc23699936d0aa012615593c21848cd8c1cd886700aad59ef02d1fb8b633670de9462687f774f58275afb93ce0e8026", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4796e10ea62bdb5a1e55573f14bc7c72971b0264cd629b79f451a069bcf12550ed472a15f7c155ae26f09a7342f96ff3997e209faaa740e20463f4329c85ef1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-28 18:44:57.447229) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:44:57.447618) ========= 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=199fb4c72b90437b990b419b000001c5-1503966025.700516-1686551440 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004449 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:44:57.532364) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-28 18:44:57.532721) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-28 18:44:57.533084) ========= ========= Total master_lag: 0.35 =========