builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-038 starttime: 1503969806.36 results: success (0) revision: 1b4c59eef820b46eb0037aca68f83a15088db45f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:26.365465) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:26.365878) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:26.366184) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:26.385389) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:26.385675) ========= 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=8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-28 18:23:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.8M=0s 2017-08-28 18:23:26 (42.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131000 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:26.531612) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:26.534973) ========= 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=8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015118 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:26.590210) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:26.590507) ========= 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=8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-28 18:23:26,649 truncating revision to first 12 chars 2017-08-28 18:23:26,649 Setting DEBUG logging. 2017-08-28 18:23:26,649 attempt 1/10 2017-08-28 18:23:26,649 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:27,146 unpacking tar archive at: mozilla-central-1b4c59eef820/testing/mozharness/ program finished with exit code 0 elapsedTime=0.785134 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:27.399873) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:27.400148) ========= script_repo_revision: 1b4c59eef820b46eb0037aca68f83a15088db45f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:27.400506) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:27.400789) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:27.414874) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-08-28 18:23:27.415145) ========= /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=8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:23:27 INFO - MultiFileLogger online at 20170828 18:23:27 in /builds/slave/test 18:23:27 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:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:23:27 INFO - {'append_to_log': False, 18:23:27 INFO - 'base_work_dir': '/builds/slave/test', 18:23:27 INFO - 'blob_upload_branch': 'Firefox', 18:23:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:23:27 INFO - 'branch': 'Firefox', 18:23:27 INFO - 'buildbot_json_path': 'buildprops.json', 18:23:27 INFO - 'code_coverage': False, 18:23:27 INFO - 'config_files': ('talos/linux_config.py',), 18:23:27 INFO - 'default_actions': ('clobber', 18:23:27 INFO - 'read-buildbot-config', 18:23:27 INFO - 'download-and-extract', 18:23:27 INFO - 'populate-webroot', 18:23:27 INFO - 'create-virtualenv', 18:23:27 INFO - 'install', 18:23:27 INFO - 'setup-mitmproxy', 18:23:27 INFO - 'run-tests'), 18:23:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:23:27 INFO - 'disable_ccov_upload': False, 18:23:27 INFO - 'download_minidump_stackwalk': True, 18:23:27 INFO - 'download_symbols': 'ondemand', 18:23:27 INFO - 'enable_stylo': False, 18:23:27 INFO - 'enable_webrender': False, 18:23:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:23:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:23:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:23:27 INFO - 'gecko_profile': False, 18:23:27 INFO - 'gecko_profile_interval': 0, 18:23:27 INFO - 'installer_path': 'installer.exe', 18:23:27 INFO - 'log_level': 'info', 18:23:27 INFO - 'log_name': 'talos', 18:23:27 INFO - 'log_to_console': True, 18:23:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:23:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:23:27 INFO - 'opt_config_files': (), 18:23:27 INFO - 'pip_index': False, 18:23:27 INFO - 'suite': 'dromaeojs-e10s', 18:23:27 INFO - 'system_bits': '32', 18:23:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:23:27 INFO - 'title': 'talos-linux64-ix-038', 18:23:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:23:27 INFO - 'use_talos_json': True, 18:23:27 INFO - 'verify': 'False', 18:23:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:23:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:23:27 INFO - 'work_dir': 'build'} 18:23:27 INFO - [mozharness: 2017-08-29 01:23:27.625992Z] Running clobber step. 18:23:27 INFO - Running pre-action listener: _resource_record_pre_action 18:23:27 INFO - Running main action method: clobber 18:23:27 INFO - rmtree: /builds/slave/test/build 18:23:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:23:28 INFO - Running post-action listener: _resource_record_post_action 18:23:28 INFO - [mozharness: 2017-08-29 01:23:28.646072Z] Finished clobber step (success) 18:23:28 INFO - [mozharness: 2017-08-29 01:23:28.646171Z] Running read-buildbot-config step. 18:23:28 INFO - Running pre-action listener: _resource_record_pre_action 18:23:28 INFO - Running main action method: read_buildbot_config 18:23:28 INFO - Using buildbot properties: 18:23:28 INFO - { 18:23:28 INFO - "project": "", 18:23:28 INFO - "product": "firefox", 18:23:28 INFO - "who": "kwierso@gmail.com", 18:23:28 INFO - "installer_path": "public/build/target.tar.bz2", 18:23:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 18:23:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:23:28 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 18:23:28 INFO - "stage_platform": "linux64-stylosequential", 18:23:28 INFO - "basedir": "/builds/slave/test", 18:23:28 INFO - "buildnumber": 176, 18:23:28 INFO - "platform": "ubuntu64_hw_styloseq", 18:23:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:23:28 INFO - "slavebuilddir": "test", 18:23:28 INFO - "taskId": "ZJDHoXKcR8-CoEeJanVj9w", 18:23:28 INFO - "branch": "mozilla-central", 18:23:28 INFO - "script_repo_revision": "production", 18:23:28 INFO - "revision": "1b4c59eef820b46eb0037aca68f83a15088db45f", 18:23:28 INFO - "slavename": "talos-linux64-ix-038", 18:23:28 INFO - "repo_path": "mozilla-central" 18:23:28 INFO - } 18:23:28 INFO - Finding installer, test and symbols from parent task. 18:23:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZJDHoXKcR8-CoEeJanVj9w'}, attempt #1 18:23:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZJDHoXKcR8-CoEeJanVj9w'}, attempt #1 18:23:29 INFO - Task dependencies: A3hmUCGWRMeTZ6zQMbT4dg 18:23:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg'}, attempt #1 18:23:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.tar.bz2 18:23:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.test_packages.json 18:23:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.crashreporter-symbols.zip 18:23:29 INFO - Running post-action listener: _resource_record_post_action 18:23:29 INFO - [mozharness: 2017-08-29 01:23:29.994052Z] Finished read-buildbot-config step (success) 18:23:29 INFO - [mozharness: 2017-08-29 01:23:29.994268Z] Running download-and-extract step. 18:23:29 INFO - Running pre-action listener: _resource_record_pre_action 18:23:29 INFO - Running main action method: download_and_extract 18:23:29 INFO - mkdir: /builds/slave/test/build/tests 18:23: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:23:29 INFO - trying https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.test_packages.json 18:23:29 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:29 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:30 INFO - Downloaded 989 bytes. 18:23:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:23:30 INFO - Using the following test package requirements: 18:23:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:23:30 INFO - u'common': [u'target.common.tests.zip'], 18:23:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:23:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:23:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:23:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:23:30 INFO - u'mozbase': [u'target.common.tests.zip'], 18:23:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:23:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:23:30 INFO - u'web-platform': [u'target.common.tests.zip', 18:23:30 INFO - u'target.web-platform.tests.tar.gz'], 18:23:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:23:30 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:23:30 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:30 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:30 INFO - Fetch https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.common.tests.zip into memory 18:23:31 INFO - Content-Length response header: 54977777 18:23:31 INFO - Bytes received: 54977777 18:23:42 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:23:42 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:42 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:42 INFO - Fetch https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.common.tests.zip into memory 18:23:44 INFO - Content-Length response header: 54977777 18:23:44 INFO - Bytes received: 54977777 18:23:55 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:55 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:55 INFO - Fetch https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.talos.tests.zip into memory 18:23:56 INFO - Content-Length response header: 14496611 18:23:56 INFO - Bytes received: 14496611 18:23:57 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:57 INFO - trying https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.tar.bz2 18:23:57 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:57 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.357803Z] Finished download-and-extract step (success) 18:24:27 INFO - [mozharness: 2017-08-29 01:24:27.358031Z] 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.379275Z] Finished populate-webroot step (success) 18:24:27 INFO - [mozharness: 2017-08-29 01:24:27.379364Z] 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:27 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 0x21e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2550f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e130>, '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 0x254f030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254f4c0>, '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': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', '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': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', 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 0x21e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2550f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e130>, '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 0x254f030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254f4c0>, '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': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', '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': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', 18:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:29 INFO - '_': '/tools/buildbot/bin/python'} 18:24:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:29 INFO - Downloading/unpacking psutil>=3.1.1 18:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:24:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:24:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:24:30 INFO - Installing collected packages: psutil 18:24:30 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 0x21e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2550f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e130>, '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 0x254f030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254f4c0>, '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': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', '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': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', 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:34 INFO - Return code: 0 18:24:34 INFO - Installing jsonschema==2.5.1 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', '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 0x21e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2550f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e130>, '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 0x254f030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254f4c0>, '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': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', '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', 'jsonschema==2.5.1'] 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 jsonschema==2.5.1 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': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', 18:24:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:34 INFO - '_': '/tools/buildbot/bin/python'} 18:24:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:34 INFO - Downloading/unpacking 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:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:24:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 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:35 INFO - Downloading functools32-3.2.3-2.tar.gz 18:24:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:24:35 INFO - warning: no files found matching '*.txt' 18:24:35 INFO - no previously-included directories found matching 'build' 18:24:35 INFO - no previously-included directories found matching 'dist' 18:24:35 INFO - no previously-included directories found matching '.git*' 18:24:35 INFO - Installing collected packages: jsonschema, functools32 18:24:36 INFO - Running setup.py install for functools32 18:24:36 INFO - warning: no files found matching '*.txt' 18:24:36 INFO - no previously-included directories found matching 'build' 18:24:36 INFO - no previously-included directories found matching 'dist' 18:24:36 INFO - no previously-included directories found matching '.git*' 18:24:36 INFO - Successfully installed jsonschema functools32 18:24:36 INFO - Cleaning up... 18:24:36 INFO - Return code: 0 18:24:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:24:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x21e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2550f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e130>, '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 0x254f030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254f4c0>, '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': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', '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:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:24:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:24:36 INFO - Using env: {'DISPLAY': ':0', 18:24:36 INFO - 'HOME': '/home/cltbld', 18:24:36 INFO - 'LANG': 'en_US.UTF-8', 18:24:36 INFO - 'LANGUAGE': 'en_US:en', 18:24:36 INFO - 'LOGNAME': 'cltbld', 18:24:36 INFO - 'MAIL': '/var/mail/cltbld', 18:24:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:36 INFO - 'MOZ_NO_REMOTE': '1', 18:24:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:36 INFO - 'NO_EM_RESTART': '1', 18:24:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:36 INFO - 'PWD': '/builds/slave/test', 18:24:36 INFO - 'SHELL': '/bin/bash', 18:24:36 INFO - 'SHLVL': '1', 18:24:36 INFO - 'TERM': 'linux', 18:24:36 INFO - 'TMOUT': '86400', 18:24:36 INFO - 'USER': 'cltbld', 18:24:36 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', 18:24:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:36 INFO - '_': '/tools/buildbot/bin/python'} 18:24:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:24:36 INFO - Cleaning up... 18:24:36 INFO - Return code: 0 18:24:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:24:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x21e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2550f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e130>, '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 0x254f030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254f4c0>, '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': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', '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:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:24:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:24:36 INFO - Using env: {'DISPLAY': ':0', 18:24:36 INFO - 'HOME': '/home/cltbld', 18:24:36 INFO - 'LANG': 'en_US.UTF-8', 18:24:36 INFO - 'LANGUAGE': 'en_US:en', 18:24:36 INFO - 'LOGNAME': 'cltbld', 18:24:36 INFO - 'MAIL': '/var/mail/cltbld', 18:24:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:36 INFO - 'MOZ_NO_REMOTE': '1', 18:24:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:36 INFO - 'NO_EM_RESTART': '1', 18:24:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:36 INFO - 'PWD': '/builds/slave/test', 18:24:36 INFO - 'SHELL': '/bin/bash', 18:24:36 INFO - 'SHLVL': '1', 18:24:36 INFO - 'TERM': 'linux', 18:24:36 INFO - 'TMOUT': '86400', 18:24:36 INFO - 'USER': 'cltbld', 18:24:36 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', 18:24:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:36 INFO - '_': '/tools/buildbot/bin/python'} 18:24:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:36 INFO - Downloading/unpacking 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:37 INFO - Downloading blobuploader-1.2.4.tar.gz 18:24:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:24:37 INFO - Downloading/unpacking requests==1.2.3. (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 - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:24:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:24:38 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:38 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:38 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:38 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:38 INFO - Downloading docopt-0.6.1.tar.gz 18:24:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:24:38 INFO - Installing collected packages: blobuploader, requests, docopt 18:24:38 INFO - Running setup.py install for blobuploader 18:24:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:24:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:24:38 INFO - Running setup.py install for requests 18:24:38 INFO - Running setup.py install for docopt 18:24:38 INFO - Successfully installed blobuploader requests docopt 18:24:38 INFO - Cleaning up... 18:24:38 INFO - Return code: 0 18:24:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24:38 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:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:38 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:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:38 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 0x21e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2550f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e130>, '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 0x254f030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254f4c0>, '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': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', '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:38 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:38 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:38 INFO - Using env: {'DISPLAY': ':0', 18:24:38 INFO - 'HOME': '/home/cltbld', 18:24:38 INFO - 'LANG': 'en_US.UTF-8', 18:24:38 INFO - 'LANGUAGE': 'en_US:en', 18:24:38 INFO - 'LOGNAME': 'cltbld', 18:24:38 INFO - 'MAIL': '/var/mail/cltbld', 18:24:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:38 INFO - 'MOZ_NO_REMOTE': '1', 18:24:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:38 INFO - 'NO_EM_RESTART': '1', 18:24:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:38 INFO - 'PWD': '/builds/slave/test', 18:24:38 INFO - 'SHELL': '/bin/bash', 18:24:38 INFO - 'SHLVL': '1', 18:24:38 INFO - 'TERM': 'linux', 18:24:38 INFO - 'TMOUT': '86400', 18:24:38 INFO - 'USER': 'cltbld', 18:24:38 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', 18:24:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:38 INFO - '_': '/tools/buildbot/bin/python'} 18:24:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:24:39 INFO - Running setup.py (path:/tmp/pip-G6rgXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:24:39 INFO - Running setup.py (path:/tmp/pip-o6Q1kb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:24:39 INFO - Running setup.py (path:/tmp/pip-Snydqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:24:39 INFO - Running setup.py (path:/tmp/pip-QYcEke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:24:39 INFO - Running setup.py (path:/tmp/pip-z17eqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:24:39 INFO - Running setup.py (path:/tmp/pip-a3Z_4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:24:39 INFO - Running setup.py (path:/tmp/pip-2orDLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:24:39 INFO - Running setup.py (path:/tmp/pip-9BS83f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:24:39 INFO - Running setup.py (path:/tmp/pip-v1jq8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:24:39 INFO - Running setup.py (path:/tmp/pip-m_iUei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:24:39 INFO - Running setup.py (path:/tmp/pip-7AmlzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:24:39 INFO - Running setup.py (path:/tmp/pip-kxnxaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:24:39 INFO - Running setup.py (path:/tmp/pip-cGdxq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:24:39 INFO - Running setup.py (path:/tmp/pip-SqLgm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:40 INFO - Running setup.py (path:/tmp/pip-f38lVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:24:40 INFO - Running setup.py (path:/tmp/pip-rgmFbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:24:40 INFO - Running setup.py (path:/tmp/pip-U22N0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:24:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:24:40 INFO - Running setup.py install for manifestparser 18:24:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:24:40 INFO - Running setup.py install for mozcrash 18:24:40 INFO - Running setup.py install for mozdebug 18:24:40 INFO - Running setup.py install for mozdevice 18:24:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:24:40 INFO - Running setup.py install for mozfile 18:24:40 INFO - Running setup.py install for mozhttpd 18:24:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:24:40 INFO - Running setup.py install for mozinfo 18:24:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:24:40 INFO - Running setup.py install for mozInstall 18:24:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:24:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:24:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:24:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:24:41 INFO - Running setup.py install for mozleak 18:24:41 INFO - Running setup.py install for mozlog 18:24:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:24:41 INFO - Running setup.py install for moznetwork 18:24:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:24:41 INFO - Running setup.py install for mozprocess 18:24:41 INFO - Running setup.py install for mozprofile 18:24:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:24:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:24:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:24:41 INFO - Running setup.py install for mozrunner 18:24:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:24:41 INFO - Running setup.py install for mozscreenshot 18:24:41 INFO - Running setup.py install for moztest 18:24:41 INFO - Running setup.py install for mozversion 18:24:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:24:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:24:41 INFO - Cleaning up... 18:24:41 INFO - Return code: 0 18:24:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:42 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 0x21e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2550f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e130>, '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 0x254f030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254f4c0>, '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': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', '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:42 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:42 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:42 INFO - Using env: {'DISPLAY': ':0', 18:24:42 INFO - 'HOME': '/home/cltbld', 18:24:42 INFO - 'LANG': 'en_US.UTF-8', 18:24:42 INFO - 'LANGUAGE': 'en_US:en', 18:24:42 INFO - 'LOGNAME': 'cltbld', 18:24:42 INFO - 'MAIL': '/var/mail/cltbld', 18:24:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:42 INFO - 'MOZ_NO_REMOTE': '1', 18:24:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:42 INFO - 'NO_EM_RESTART': '1', 18:24:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:42 INFO - 'PWD': '/builds/slave/test', 18:24:42 INFO - 'SHELL': '/bin/bash', 18:24:42 INFO - 'SHLVL': '1', 18:24:42 INFO - 'TERM': 'linux', 18:24:42 INFO - 'TMOUT': '86400', 18:24:42 INFO - 'USER': 'cltbld', 18:24:42 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', 18:24:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:42 INFO - '_': '/tools/buildbot/bin/python'} 18:24:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:24:42 INFO - Running setup.py (path:/tmp/pip-JSHpsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:24:42 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:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:24:42 INFO - Running setup.py (path:/tmp/pip-CzL24I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:24:42 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:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:24:42 INFO - Running setup.py (path:/tmp/pip-RndCMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:24:42 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:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:24:42 INFO - Running setup.py (path:/tmp/pip-Mk1CXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:24:42 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:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:24:42 INFO - Running setup.py (path:/tmp/pip-niEy9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:24:42 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:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:24:42 INFO - Running setup.py (path:/tmp/pip-UkxIOg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:24:42 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:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:24:42 INFO - Running setup.py (path:/tmp/pip-6zpJuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:24:42 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:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:24:42 INFO - Running setup.py (path:/tmp/pip-ZYcVga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:24:42 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:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:24:42 INFO - Running setup.py (path:/tmp/pip-pCfn52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:24:42 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:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:24:42 INFO - Running setup.py (path:/tmp/pip-YC7Yhv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:24:42 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:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:24:42 INFO - Running setup.py (path:/tmp/pip-V2VIyq-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-o2yCQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:24:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:24:43 INFO - Running setup.py (path:/tmp/pip-b2Jdh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:24:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:24:43 INFO - Running setup.py (path:/tmp/pip-2QXlJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:24:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:43 INFO - Running setup.py (path:/tmp/pip-_amnnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:24:43 INFO - Running setup.py (path:/tmp/pip-2xyxB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:24:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:24:43 INFO - Running setup.py (path:/tmp/pip-CX4iZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:24:43 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:43 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:43 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:43 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:43 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:43 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:43 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:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18: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:44 INFO - Reading from file tmpfile_stdout 18:24:44 INFO - Current package versions: 18:24:44 INFO - argparse == 1.2.1 18:24:44 INFO - blessings == 1.6 18:24:44 INFO - blobuploader == 1.2.4 18:24:44 INFO - docopt == 0.6.1 18:24:44 INFO - functools32 == 3.2.3-2 18:24:44 INFO - jsonschema == 2.5.1 18:24:44 INFO - manifestparser == 1.1 18:24:44 INFO - mozInstall == 1.13 18:24:44 INFO - mozcrash == 1.0 18:24:44 INFO - mozdebug == 0.1 18:24:44 INFO - mozdevice == 0.51 18:24:44 INFO - mozfile == 1.2 18:24:44 INFO - mozhttpd == 0.7 18:24:44 INFO - mozinfo == 0.10 18:24:44 INFO - mozleak == 0.1 18:24:44 INFO - mozlog == 3.5 18:24:44 INFO - moznetwork == 0.27 18:24:44 INFO - mozprocess == 0.25 18:24:44 INFO - mozprofile == 0.28 18:24:44 INFO - mozrunner == 6.13 18:24:44 INFO - mozscreenshot == 0.1 18:24:44 INFO - mozsystemmonitor == 0.3 18:24:44 INFO - moztest == 0.8 18:24:44 INFO - mozversion == 1.4 18:24:44 INFO - psutil == 3.1.1 18:24:44 INFO - requests == 1.2.3 18:24:44 INFO - wsgiref == 0.1.2 18:24:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24:44 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:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:44 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:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:44 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 0x21e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2550f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e130>, '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 0x254f030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254f4c0>, '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': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', '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:44 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:44 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:44 INFO - Using env: {'DISPLAY': ':0', 18:24:44 INFO - 'HOME': '/home/cltbld', 18:24:44 INFO - 'LANG': 'en_US.UTF-8', 18:24:44 INFO - 'LANGUAGE': 'en_US:en', 18:24:44 INFO - 'LOGNAME': 'cltbld', 18:24:44 INFO - 'MAIL': '/var/mail/cltbld', 18:24:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:44 INFO - 'MOZ_NO_REMOTE': '1', 18:24:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:44 INFO - 'NO_EM_RESTART': '1', 18:24:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:44 INFO - 'PWD': '/builds/slave/test', 18:24:44 INFO - 'SHELL': '/bin/bash', 18:24:44 INFO - 'SHLVL': '1', 18:24:44 INFO - 'TERM': 'linux', 18:24:44 INFO - 'TMOUT': '86400', 18:24:44 INFO - 'USER': 'cltbld', 18:24:44 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', 18:24:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:44 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 0x21e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2550f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e130>, '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 0x254f030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254f4c0>, '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': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', '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': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', 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.665585Z] Finished create-virtualenv step (success) 18:24:47 INFO - [mozharness: 2017-08-29 01:24:47.666060Z] 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.710932Z] Finished install step (success) 18:25:05 INFO - [mozharness: 2017-08-29 01:25:05.711183Z] 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.712298Z] Finished setup-mitmproxy step (success) 18:25:05 INFO - [mozharness: 2017-08-29 01:25:05.712517Z] 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 0x254e130>, '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 0x254f030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254f4c0>, '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-038', '--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-038 --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_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 - '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 - 'STYLO_FORCE_ENABLED': '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': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', 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-038', '--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:53315' 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:53315/getInfo.html -profile /tmp/tmpI2Cmo3/profile 18:25:06 INFO - TEST-INFO | started process 31059 (/builds/slave/test/build/application/firefox/firefox http://localhost:53315/getInfo.html) 18:25:12 INFO - TEST-INFO | 31059: exit 0 18:25:12 INFO - Browser initialized. 18:25:12 INFO - Running cycle 1/1 for dromaeo_css test... 18:25:12 INFO - Using env: {'DISPLAY': ':0', 18:25:12 INFO - 'HOME': '/home/cltbld', 18:25:12 INFO - 'JSGC_DISABLE_POISONING': '1', 18:25:12 INFO - 'LANG': 'en_US.UTF-8', 18:25:12 INFO - 'LANGUAGE': 'en_US:en', 18:25:12 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:25:12 INFO - 'LOGNAME': 'cltbld', 18:25:12 INFO - 'MAIL': '/var/mail/cltbld', 18:25:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:25:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:25:12 INFO - 'MOZ_CRASHREPORTER': '1', 18:25:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:12 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:25:12 INFO - 'MOZ_NO_REMOTE': '1', 18:25:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:25:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:12 INFO - 'NO_EM_RESTART': '1', 18:25:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:12 INFO - 'PWD': '/builds/slave/test', 18:25:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:25:12 INFO - 'RUST_BACKTRACE': 'full', 18:25:12 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:25:12 INFO - 'SHELL': '/bin/bash', 18:25:12 INFO - 'SHLVL': '1', 18:25:12 INFO - 'STYLO_FORCE_ENABLED': '1', 18:25:12 INFO - 'TERM': 'linux', 18:25:12 INFO - 'TMOUT': '86400', 18:25:12 INFO - 'USER': 'cltbld', 18:25:12 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', 18:25:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:12 INFO - '_': '/tools/buildbot/bin/python'} 18:25:12 INFO - TEST-INFO | started process 31243 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpI2Cmo3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:25:13 INFO - PID 31243 | 18:25:13 INFO - PID 31243 | (/builds/slave/test/build/application/firefox/firefox:31292): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:25:13 INFO - PID 31243 | 18:25:24 INFO - PID 31243 | RSS: Main: 173305856 18:25:24 INFO - PID 31243 | 18:28:22 INFO - PID 31243 | Cycle 1(1): loaded http://localhost:53315/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53315/tests/dromaeo/cssquery-ext.html) 18:28:23 INFO - PID 31243 | RSS: Main: 177954816 18:28:23 INFO - PID 31243 | 18:31:25 INFO - PID 31243 | Cycle 1(1): loaded http://localhost:53315/tests/dromaeo/cssquery-ext.html (next: http://localhost:53315/tests/dromaeo/cssquery-jquery.html) 18:31:26 INFO - PID 31243 | RSS: Main: 173961216 18:31:26 INFO - PID 31243 | 18:34:28 INFO - PID 31243 | Cycle 1(1): loaded http://localhost:53315/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53315/tests/dromaeo/cssquery-mootools.html) 18:34:28 INFO - PID 31243 | RSS: Main: 176119808 18:34:28 INFO - PID 31243 | 18:37:30 INFO - PID 31243 | Cycle 1(1): loaded http://localhost:53315/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53315/tests/dromaeo/cssquery-prototype.html) 18:37:30 INFO - PID 31243 | RSS: Main: 174596096 18:37:30 INFO - PID 31243 | 18:40:34 INFO - PID 31243 | Cycle 1(1): loaded http://localhost:53315/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53315/tests/dromaeo/cssquery-yui.html) 18:40:35 INFO - PID 31243 | RSS: Main: 177803264 18:40:35 INFO - PID 31243 | 18:43:36 INFO - PID 31243 | Cycle 1(1): loaded http://localhost:53315/tests/dromaeo/cssquery-yui.html (next: http://localhost:53315/tests/dromaeo/cssquery-dojo.html) 18:43:37 INFO - PID 31243 | RSS: Main: 178290688 18:43:37 INFO - PID 31243 | 18:43:37 INFO - PID 31243 | __start_tp_report 18:43:37 INFO - PID 31243 | _x_x_mozilla_page_load 18:43:37 INFO - PID 31243 | _x_x_mozilla_page_load_details 18:43:37 INFO - PID 31243 | |i|pagename|runs| 18:43:37 INFO - PID 31243 | |0;dojo.html;3323.1193733660584;3330.1175188575025;3340.6492318306587;3365.6634336566362;3368.1242876852025;6177.722002509887;6224.315325314212;6262.718177682003;6289.591176573525;6327.873442531147;6118.357572454895;6235.438810507054;6238.344973777756;6257.280412752523;6268.435840774329;5689.089745640698;5703.31560212774;5723.084306510959;5782.855428614276;5848.035074212749;5811.883762324751;5826.9708651456895;5829.417058294158;5840.532757379399;5850.502707269887;5568.415316391761;5686.886262274731;5740.167675687023;5853.707314634251;5860.267466566679;5704.914426283609;5767.077267637158;5773.855653608652;5815.040518314473;5836.533077353802;5671.092625179951;5727.3699893011735;5738.913916291259;5802.477777000048;5802.912956305659;6106.297775755756;6169;6178.9691051544905;6201.658908760016;6252.030935205051;6670;6750.257471678112;6754;6758.189017317954;6763.797086087426;6749;6751.831204219885;6833.282505336918;6843.555168914055;6845.281245469254;6246.281677607042;6298.6535740534255;6325.082862984841;6329.019002054735;6364.236291645032;6927.203371612329;6968.233494315621;6975.476839237078;6996;7029.859402811915;6548.508861835381;6581.111549940792;6637.336266373324;6682.665866706645;6690.431313338421;6672.866542669217;6733.158355205599;6733.158355205599;6739.292374300726;6742.157230346207;6638.701258443381;6697.7655782047395;6704.932950670528;6730;6731.966340168366;6648.434883034996;6678.7662431815625;6752.291009444036;6772.762953296611;6781.8643627127185;4882.584980276716;4941.629377796679;4949.034938187091;4966.776495057731;5014.548690617862;4393.253146965024;4396.296592545177;4407.603315701603;4441.844535441244;4469.620082293041;5746.856328591819;5834.682340049329;5897.764089436375;5908.940910590925;5926.7925622603;6530.05314229434;6636.601803891782;6663.566868153554;6743.8988415172835;6749.6962636681465;5799.33307669615;5802.738876750556;5821.359650438449;5826.475617194473;5829.795957141479;5789.305283366023;5818.069108942549;5820.35976042635;5820.94179058204;5823.970880145572;6529.706163222666;6535.477161827042;6564.6389448580785;6628.138342015507;6632.071509988606;6571.047198156336;6650.168728908887;6659.633720145244;6722.1261236040955;6731.46148308144;6556.442702370352;6603.042558829039;6629.370209830058;6635.170603674541;6683.8997415039785;6616.5368424211765;6629.171353580802;6669.166354205724;6737.090492783412;6754.763583274562;6719.664016799238;6735.932640673531;6746.359095885883;6803.285655006351;6866.656667166521;6536.705848236746;6567.835804104744;6608.339166083546;6611.30581288977;6620.3710647488415;6504.577202481823;6527;6589.934100658932;6613.107230523818;6625.867482650224;6363.172787537776;6442.935570644234;6443.065755465525;6445.097686323825;6445.70994305248;6489.221293444817;6496.707648155939;6514.706838192198;6522.902156467562;6531.444827189742;6293.150424692608;6301.306856245901;6313.873722525615;6329.588576742497;6408.615483070844 18:43:37 INFO - PID 31243 | |1;ext.html;795.4710117771682;843.1231519220012;876.8947726272849;880.0187790613464;1122.4780477078168;30454;31114.53328200079;41831.581684183184;42543.787281063625;42641.78679106608;42805.35791963123;43222;43228.35157472632;43261.13477730443;43287;7757.806054848632;7929.048514178277;7940.920590794105;7943.404244681672;7944;7799.142094369615;7919.722809701661;7925.920740792576;7928.048634163908;7930.6431210595665;7898.723544675938;7934.7222847200355;7938.126806051329;7938.24586664266;7938.364930805551;7879.290863822255;7913.604319784016;7915.564643944581;7917.0895347034675;7919.049714034324;7796.298333149987;7841.137474877759;7847.058352997619;7849.136594974549;7855.7250496232355;37331.25337493235;37363.626363736556;37381.25237495262;37449.43825842615;37605.811970940245;3471.3230919970615;3493.7729047611824;3511.8244087795765;3537.5047493350694;3540.7698499597445;30983.38033239345;31076.37847243065;31089.84455077733;31144;31160.220994475094;5077.136886729228;5112.744362781883;5137.383513978347;5161.741912904377;5164.715940623264;182196.08901955537;182276;182606;183318;183404;47265;47313.763431182626;47433.762831185624;47463.52536474591;47506.76246618745;49863.50136498661;49890;49911.25133123006;49955.50044499509;50011.24983125256;13660.590182294547;14138.363773630212;14629.414823407158;14910.850891491164;14916.477923272634;12484.063695222894;12533.122681412397;12562.87437125617;12606.936965315299;12649.367531623566;13789;13835.10071845347;13862.168269903837;13963.930180349236;14097;5497.505224529811;5592;5686.630369026;5728.77084916607;5784.88430231393;977.310995747996;1004.8643433136433;1008.1330056151704;1015.5633077776627;1032.6179313653845;22931.197408090637;28230.85884570605;29010.564841527394;29150;29239.122826315244;1081.1837063017372;1082.9945850270856;1086.521930350659;1090.432974853072;1094.0591091661165;12149.331786751636;12274.877251227552;12343.876561234452;12387.876121238853;12447.190932589352;241124;241800;242181;242203;242477;52557;52953.73523132437;53010.73494632502;53047.73476132595;53179.468205318226;1379.5585412668052;1425.7504936636046;1437.4106616287272;1445.147363055802;1447.232966527742;22407.77592224122;22477.887610561844;22613.773862261165;22798.430039249153;23036.308910732703;36899.26201476009;37269.813650931574;37319.44020839744;37331.626683732815;37347.81326093352;215496;217925;218452;218629;218953;29399.70600294055;30084.398312034074;32714.672853271164;32809.835950820096;32857.67142328642;42115.57884421239;42163.36754948617;42263.7886810564;42567.14865702607;42604.78697606616;31649.367012660077;31700.207494812814;31833.204169895012;31978;32190;31124.221894451915;31216.53175202419;31258.843705781324;31508;32085.67914320827;35174.824125879204;35186.296274074884;35305.82347088248;35356.469652954715;35374;29942.550861737527;30020;30189.849050754605;30396.696033039385;30440.391192176474;22928.54142917099;23232.3030309091;23441.062357505507;23546.058157673502;23709.64435533503 18:43:37 INFO - PID 31243 | |2;jquery.html;549.6784381137035;549.8570371703358;556.7605929450335;567.4382361462153;571.9771209151634;21394.786052139494;22172;22204.333869983908;22223.888880555576;22244.666330005024;23767.524649507042;25691.229263122135;25743.0989915353;25750.613740793902;25753.96984120633;6553.803385898419;6679.231888332855;6682.331766823328;6684.83287917801;6685.699143538553;6068.27180738312;6551.737930482775;6661;6676.599404035749;6681.198256209262;6640.833979150536;6648.102506161679;6655.134832471772;6657.667116644173;6658.267590565034;6623.503237257225;6627.204735431731;6643.501737369693;6645.169353830771;6656.167979002625;6134;6135.9693201534155;6144.723487443076;6151.569390142692;6151.907721384183;28489.145325640056;28881;28997;29145.854270728723;29410.11769646914;2014.6977953306975;2054.6404379233572;2056.4447599148148;2059.3101311060664;2061.371281759063;18878.528036799053;19367;19367.51581210481;19421.22315107394;19425.4172374829;2335.1476711000532;2344.1443872986238;2388.820838437124;2391.892364843586;2421.806255499556;130577.34711326478;133163.33418332943;134501;134594;136989.3150534241;14502.347394367278;14682.265886705622;14836;15136.772948405784;15369.923150384177;22243;22741.772582274298;22914.65628015581;22927.197548085736;22953.655695164587;9798.363106398061;9899.059589339002;9998.600055997678;10017.198624110053;10079.143272821892;9095.31785116119;9107.22588579978;9112.8633070504;9214.539273036322;9228.7692807679;9187.724368268962;9224;9298.72103836886;9421.481818499911;9422.481763502936;2656.296081538394;2689.3411114276737;2716.3480764616743;2726.631904692841;2773.309889407272;950.9476978766235;953.6423841059602;964.1708131007329;974.6539978307741;983.1151963233004;19475.610487790163;19791.703124453146;19974.800251997294;20162.89918550398;23330.183443579393;898.6585097662846;985.2265971212564;1021.5403068619152;1027.0653705128448;1027.9229057820694;10592.629257975934;10604.89395106039;10809.135269178447;10854.891177100695;10857.891421085846;506136;515898;523441;532553;540976;116305;116565.4171729136;117148.41425792816;118259;119514;1197.2722844617633;1213.5206593395735;1239.3988915376035;1241.453760345445;1251.69959209789;16886.915565422587;17193.57016074608;17649.38227162043;17669;17880;50436.991260173854;50699.746501267255;53114;54572.45427545832;57146.42853571411;69293.65353173202;69337;69615.65192174006;70011.29988700252;70518;14434;14468.27658617044;14530.346134424188;14536.41854325815;14604.342804573607;22887.771122288563;22890.65664015008;22984.885075574515;23035.308940731804;23066.769332306463;17934.282628694706;18121.90939045349;18126.5468363292;18219.908900455943;18241;16337.754933675767;16391;16509.58726031859;17126.914365428092;17287.135643217538;17546.561335966704;18563.164657590172;18740;18742;18777.061146942873;14398.496052638107;14427.99004069747;14476.855231447551;14490.058146220463;14637.121772693672;17811.931284122995;17837.286508539513;17872.642547149244;18146.18342174632;18175.81824181741 18:43:37 INFO - PID 31243 | |3;mootools.html;1288.1755676367125;1322.927239001855;1330.275000124933;1334.0728193905234;1340.7653660609396;14245.358958846851;14298.142111473293;14918.25408729562;14930.402783888645;14935.925320373397;15165.469208577702;15469.922650386761;15482.767758483631;15489.767653485207;15491.457798977039;2668.159529748128;2702.702702702701;2702.729727027301;2702.9864850675817;2703.7431444012786;2587.935301617456;2660.5344064788683;2661.6672915885515;2662.214646679234;2670.3724624713177;2628.6582744243224;2639.986800065997;2656.150031989764;2664.307280107176;2668.1862032080207;2618.410857557044;2646.087099950514;2665.7734092602054;2673.866306684658;2674.4784766970447;2662.3077999720017;2674.251209661298;2677.8794954227105;2678.0760637580197;2684.6912605050475;13868.791968120486;14020.92989535056;14077.366518506691;14081;14090.225037622926;7458.4033277337685;7782.571958542278;7785.026871641045;7797.610119494003;7800.141984381714;5172.482751724798;5246.580273578104;5302.522772950414;5386.649867758583;5389.326334208224;1874.7281644161656;1880.8401285890716;1898.211017299415;1908.2557802457052;1939.8254157125784;24759.381015474577;24984.375390615198;25009.249722508353;25058.87470562654;25158.245252642268;6205.937940620536;6325.683715814191;6387.808365749035;6436.903446448307;6444.516661250331;16230.188490575423;16241.918790405973;16243.269052892649;16247.431339903047;16247.51257462278;7482.551046937201;7626.389888808882;7643.388528917673;7643.617819109022;7643.847123057508;5874.471297583238;6029.487493563009;6276.843078923063;7040.471964602675;7091.680874360666;4806.759662016885;4874.268859671007;4928.827491037868;4951;5015.269731942547;1359.7960305954186;1370.2532119994628;1382.7372799168327;1395.8813500852339;1399.3632897031825;869.9739007829776;895.9462432254086;907.3693782820876;914.3005600715325;919.0579655852804;7614.6954121835615;7679.500832445873;7695.615219239127;7718.884216736754;7770.067591889009;690.4683393786756;713.9107611548557;716.7885473785246;717.3006318839194;721.7401735375261;5012.223105418666;5897.2038774765715;5989.610675306179;6013.308469525973;6104.969475152596;20053;20784.27255046066;20811.1675532977;20885;21255.468613284484;20243.39269821908;20269.391918242476;20362.18551257933;20604.27885024017;20618.58762824765;10738.087262582769;10769.9461502692;11182.66452006441;11332.660020199408;11338.43307834605;8747.300215982834;8891.377603567687;8953.194212520904;8999.685011024845;9062.909370906207;17244;17632.44329572179;17875.19561619704;17970.730439043164;17981.280748769903;17267.65464690674;17754.91122544379;18127.184276707845;18167.18247678884;18175.18211680451;10424.843627345444;11429.142814289127;11699.824502632297;11702.2978621283;11707.063434925016;13544.458221671417;13661.863381366456;13683.794743078663;13693.315334233448;13760.587182384545;10606.893931060591;10658.62694805678;10708.946455267675;10892.183086268404;11181.888181118085;10626.787464250827;10803.405812680377;10967.616133435291;11007.119430445386;11117;9738.172255358373;9779.364341317792;9852.901470985198;9876.950615246878;9882.209423246266;6527.836804079936;6592.24189218246;6602.8349291268105;6607.5044371673275;6760.661966901734;6722.9663851680425;6796.490263230178;6808.080909077409;6828.658567071528;6895.310468953065 18:43:37 INFO - PID 31243 | |4;prototype.html;501.5728512822;511.2714382005642;527.965682230655;531.0494215354515;533.4425525326034;6370;6374.872502549946;6380.266269379029;6390.776322828702;6413;6225.097360882669;6271.811845644626;6272.435480806726;6295.559310848231;6308.873822523558;5120.974395128019;5152.793888244466;5191;5191.506806853352;5222.451642577532;4852.539008794159;4960.479149689285;5026.642806442258;5157.149070403379;5189.844304670866;4903.455411545369;4955.256711493287;4965.776540055706;4991.201407774757;5007.474215207405;4930.753462326905;5001.549860512535;5001.974595207992;5015.322931404268;5021.322121513585;4334.739915605074;4463.040446304028;4464.977675111636;4474.507804141542;4486.55134486552;6143.447089761943;6147.600405973598;6147.815565533041;6154.599951003171;6167.814965551041;5695.202671625976;5699.344575373843;5699.914501282443;5749.482546570829;5752.654840709529;6604.966975165142;6607.315134640684;6647.734090636369;6665.633390163539;6700.966495167493;4889.437714662824;4899.436564795059;4903.092927808331;4905.8773530661665;4911.214205727102;6634.502412319095;6665.433623098568;6673.79657764271;6796.898046529355;6899;4791.736454494967;4916.975415122902;4988.850334489971;4990.17662085759;5013.473585273567;5025.723585202849;5036.874078148076;5039.168537191323;5052.191649336089;5091.963214714144;5654.97172514143;5655.660660360391;5707.229524014288;5709.571782116358;5736.627119237236;6501.31736167705;6563.540552161303;6585.3085426029575;6591.868162636721;6617.172853393326;1748.5453782016514;1760.4102625620305;1769.8584113270908;1775.7392251501578;1781.714925611896;1132.6262333429947;1199.4542483170312;1210.5218438716659;1211.7515909238584;1227.5519435406013;5835.883282334415;5856.94143058564;5886.293644762656;5891.3814049525035;5898.852528686732;6699.631520266432;6703.229128650268;6732.004258105097;6745.898811517831;6790.5925644461495;5780.653160810365;5807.332156801937;5823.592348535646;5830.425785038016;5895.528357731371;3490.7782276203125;3498.5801703795705;3506.4915587239707;3517.419625761772;3562.857485700543;6508.60948343101;6533.18335208099;6544.869102618016;6568.751937132025;6636.30318816527;6943.930560694429;6964.26875178099;6985.476089293324;7002.824929376807;7050.5769653820935;6023.5783495156675;6607.819419591523;6615.933840661628;6657.101291325609;6668.433183179387;6618.90071648916;6619.635920024445;6641.236257830411;6739.797806065826;6752.966235168793;6662.866742665216;6743.2582415935185;6748.797536073926;6793.150856142982;6797.9320206799275;6510.153680021567;6584.144061272004;6596.208454985433;6623.503237257321;6635.668216589054;4908.233036106927;4994.275830004702;5013.323201367914;5034.395872495324;5046.318746969112;6558.704858281484;6670.83322916931;6750.426213771884;6864.38220560152;6886.208086070166;6490.513211509061;6583.34166583357;6597.703103360264;6607.306232845482;6653.700583473658;6445.709943052668;6492.48060155177;6507.869842603216;6508.739650413931;6519.282878883224;6500.497308536342;6524.706388212637;6546.18172728409;6552.737890484327;6566.146400968035;6596.637184954873;6649.73401063952;6652.201735791736;6661.300563440963;6708.966455167693;6369.808905732835;6381.649009304533;6400.615963042233;6407;6417.454516365974 18:43:37 INFO - PID 31243 | |5;yui.html;863.6516177428589;870.9085546017668;885.3890815337417;888.4935586715575;892.9196372326492;16610.41863534775;16976.490705278848;16981.660366792672;17095.31618735249;19067.713984290247;20217.898910505464;20828.271010514636;20835;20917.686234706493;20941;931.5994122527311;933.551895090357;939.7180845746249;940.4780346907463;941.9670311539097;901.6123067081153;935.3966691484022;935.8830146231721;937.8780758501385;938.1275413865103;1005.7234260578363;1005.8893521712606;1006.9748256293578;1007.2646967713573;1008.2488546033215;1004.2618675273668;1004.3321191407705;1004.3772860826261;1004.5027711282962;1004.5278719002057;729.1141263365037;736.8047467421138;736.8268456912622;738.4535443771624;739.0318682525908;15414.306356213885;15524.689506209925;15614;15651.686966260611;16497.91751041249;20579.79420205809;21209.575808483743;21256;21267.5746485071;21277.14891404342;15706.450274240347;16006.5998350041;16337.673246535122;16383.344666213337;16407.261673224733;3670.06413364593;3701.3707669695946;3711.1278849470355;3712.962870371289;3714.1457464783252;125992;128919.71080289171;129485;130267.34866325703;132015;14188;14232.786508202385;14573.781393279109;14721.55835324942;14722.337494812758;29660;29715.811367545488;30086.24784380408;30111.698883011326;30159;8179.468334558234;8220.301274391624;8393.748187554384;8406.663733450594;8415.368847336475;4268.381084742695;4361.607455328972;4370.2570563004365;4372.23485889968;4372.584604462571;4959.82640607577;5012.7994880205115;5053.418856831511;5062.443131255559;5106.617003724736;885.8671199320154;908.246155690775;914.3736540469799;917.1104029091771;917.1470532404794;659.7492952677952;671.1912145864222;675.1594265139933;679.911611490513;683.596677960006;11228.606998755004;11242.213045086766;11255.099592032619;11681.123915706356;12106.394680265952;661.7022339947043;669.1100835888257;669.4242951062083;673.8921772516373;682.3483573187594;5105.693658380509;5233.842984710464;5313.521783039468;5333.759980800795;5422.755975981011;111811.4409427964;111869.44065279621;112661;113765;113962;100358;103240;103498.48250758697;105529.47235263775;106648;83448;86046.56976715202;86403.56798215969;89072;89210;11779.705507362385;12520.436580353724;12536;12817.487300507875;12980.391960853762;38966.80516597399;39258.803705981285;39415;40535.797321013204;41122;71441.6427917857;71773;72499.82500524992;73147.63426183048;73707.63146184235;18646.627067458845;19435.51411214731;19442.708359374337;19656.606867862847;19732.704009440156;20184.79815201829;20405.591888161856;20855.47861303419;20965.05657245397;21058;18730.531736706693;19305.710414343514;19339.90330048341;19366;19649.901750491157;17133.143342832558;17207.31170753206;17264.13679316004;18721;19638.803611964267;18230.635387291914;18424.263029478672;18812.05939702982;18941.337053203624;19392.2243110274;12037.578684745991;12292.631221063382;12491.312977786309;12726.363681816058;12755.362231888183;13546.661333466347;14051.929740351641;14141.151530908179;14260.358283877044;14367.640808979859 18:43:37 INFO - PID 31243 | __end_tp_report 18:43:37 INFO - PID 31243 | __start_cc_report 18:43:37 INFO - PID 31243 | _x_x_mozilla_cycle_collect,348 18:43:37 INFO - PID 31243 | __end_cc_report 18:43:37 INFO - PID 31243 | __startTimestamp1503971017087__endTimestamp 18:43:37 INFO - PID 31243 | 18:43:37 INFO - PID 31243 | ------- Summary: start ------- 18:43:37 INFO - PID 31243 | Number of tests: 6 18:43:37 INFO - PID 31243 | 18:43:37 INFO - PID 31243 | [#0] dojo.html Cycles:165 Average:6174.43 Median:6443.07 stddev:746.96 (11.6%) stddev-sans-first:714.98 18:43:37 INFO - PID 31243 | Values: 3323.1 3330.1 3340.6 3365.7 3368.1 6177.7 6224.3 6262.7 6289.6 6327.9 6118.4 6235.4 6238.3 6257.3 6268.4 5689.1 5703.3 5723.1 5782.9 5848.0 5811.9 5827.0 5829.4 5840.5 5850.5 5568.4 5686.9 5740.2 5853.7 5860.3 5704.9 5767.1 5773.9 5815.0 5836.5 5671.1 5727.4 5738.9 5802.5 5802.9 6106.3 6169.0 6179.0 6201.7 6252.0 6670.0 6750.3 6754.0 6758.2 6763.8 6749.0 6751.8 6833.3 6843.6 6845.3 6246.3 6298.7 6325.1 6329.0 6364.2 6927.2 6968.2 6975.5 6996.0 7029.9 6548.5 6581.1 6637.3 6682.7 6690.4 6672.9 6733.2 6733.2 6739.3 6742.2 6638.7 6697.8 6704.9 6730.0 6732.0 6648.4 6678.8 6752.3 6772.8 6781.9 4882.6 4941.6 4949.0 4966.8 5014.5 4393.3 4396.3 4407.6 4441.8 4469.6 5746.9 5834.7 5897.8 5908.9 5926.8 6530.1 6636.6 6663.6 6743.9 6749.7 5799.3 5802.7 5821.4 5826.5 5829.8 5789.3 5818.1 5820.4 5820.9 5824.0 6529.7 6535.5 6564.6 6628.1 6632.1 6571.0 6650.2 6659.6 6722.1 6731.5 6556.4 6603.0 6629.4 6635.2 6683.9 6616.5 6629.2 6669.2 6737.1 6754.8 6719.7 6735.9 6746.4 6803.3 6866.7 6536.7 6567.8 6608.3 6611.3 6620.4 6504.6 6527.0 6589.9 6613.1 6625.9 6363.2 6442.9 6443.1 6445.1 6445.7 6489.2 6496.7 6514.7 6522.9 6531.4 6293.2 6301.3 6313.9 6329.6 6408.6 18:43:37 INFO - PID 31243 | 18:43:37 INFO - PID 31243 | [#1] ext.html Cycles:180 Average:37983.78 Median:32833.75 stddev:55928.98 (170.3%) stddev-sans-first:56016.17 18:43:37 INFO - PID 31243 | Values: 795.5 843.1 876.9 880.0 1122.5 30454.0 31114.5 41831.6 42543.8 42641.8 42805.4 43222.0 43228.4 43261.1 43287.0 7757.8 7929.0 7940.9 7943.4 7944.0 7799.1 7919.7 7925.9 7928.0 7930.6 7898.7 7934.7 7938.1 7938.2 7938.4 7879.3 7913.6 7915.6 7917.1 7919.0 7796.3 7841.1 7847.1 7849.1 7855.7 37331.3 37363.6 37381.3 37449.4 37605.8 3471.3 3493.8 3511.8 3537.5 3540.8 30983.4 31076.4 31089.8 31144.0 31160.2 5077.1 5112.7 5137.4 5161.7 5164.7 182196.1 182276.0 182606.0 183318.0 183404.0 47265.0 47313.8 47433.8 47463.5 47506.8 49863.5 49890.0 49911.3 49955.5 50011.2 13660.6 14138.4 14629.4 14910.9 14916.5 12484.1 12533.1 12562.9 12606.9 12649.4 13789.0 13835.1 13862.2 13963.9 14097.0 5497.5 5592.0 5686.6 5728.8 5784.9 977.3 1004.9 1008.1 1015.6 1032.6 22931.2 28230.9 29010.6 29150.0 29239.1 1081.2 1083.0 1086.5 1090.4 1094.1 12149.3 12274.9 12343.9 12387.9 12447.2 241124.0 241800.0 242181.0 242203.0 242477.0 52557.0 52953.7 53010.7 53047.7 53179.5 1379.6 1425.8 1437.4 1445.1 1447.2 22407.8 22477.9 22613.8 22798.4 23036.3 36899.3 37269.8 37319.4 37331.6 37347.8 215496.0 217925.0 218452.0 218629.0 218953.0 29399.7 30084.4 32714.7 32809.8 32857.7 42115.6 42163.4 42263.8 42567.1 42604.8 31649.4 31700.2 31833.2 31978.0 32190.0 31124.2 31216.5 31258.8 31508.0 32085.7 35174.8 35186.3 35305.8 35356.5 35374.0 29942.6 30020.0 30189.8 30396.7 30440.4 22928.5 23232.3 23441.1 23546.1 23709.6 18:43:37 INFO - PID 31243 | 18:43:37 INFO - PID 31243 | [#2] jquery.html Cycles:180 Average:35431.86 Median:22969.27 stddev:87715.72 (381.9%) stddev-sans-first:87922.69 18:43:37 INFO - PID 31243 | Values: 549.7 549.9 556.8 567.4 572.0 21394.8 22172.0 22204.3 22223.9 22244.7 23767.5 25691.2 25743.1 25750.6 25754.0 6553.8 6679.2 6682.3 6684.8 6685.7 6068.3 6551.7 6661.0 6676.6 6681.2 6640.8 6648.1 6655.1 6657.7 6658.3 6623.5 6627.2 6643.5 6645.2 6656.2 6134.0 6136.0 6144.7 6151.6 6151.9 28489.1 28881.0 28997.0 29145.9 29410.1 2014.7 2054.6 2056.4 2059.3 2061.4 18878.5 19367.0 19367.5 19421.2 19425.4 2335.1 2344.1 2388.8 2391.9 2421.8 130577.3 133163.3 134501.0 134594.0 136989.3 14502.3 14682.3 14836.0 15136.8 15369.9 22243.0 22741.8 22914.7 22927.2 22953.7 9798.4 9899.1 9998.6 10017.2 10079.1 9095.3 9107.2 9112.9 9214.5 9228.8 9187.7 9224.0 9298.7 9421.5 9422.5 2656.3 2689.3 2716.3 2726.6 2773.3 950.9 953.6 964.2 974.7 983.1 19475.6 19791.7 19974.8 20162.9 23330.2 898.7 985.2 1021.5 1027.1 1027.9 10592.6 10604.9 10809.1 10854.9 10857.9 506136.0 515898.0 523441.0 532553.0 540976.0 116305.0 116565.4 117148.4 118259.0 119514.0 1197.3 1213.5 1239.4 1241.5 1251.7 16886.9 17193.6 17649.4 17669.0 17880.0 50437.0 50699.7 53114.0 54572.5 57146.4 69293.7 69337.0 69615.7 70011.3 70518.0 14434.0 14468.3 14530.3 14536.4 14604.3 22887.8 22890.7 22984.9 23035.3 23066.8 17934.3 18121.9 18126.5 18219.9 18241.0 16337.8 16391.0 16509.6 17126.9 17287.1 17546.6 18563.2 18740.0 18742.0 18777.1 14398.5 14428.0 14476.9 14490.1 14637.1 17811.9 17837.3 17872.6 18146.2 18175.8 18:43:37 INFO - PID 31243 | 18:43:37 INFO - PID 31243 | [#3] mootools.html Cycles:180 Average:8989.28 Median:2658.34 stddev:6350.83 (238.9%) stddev-sans-first:6342.28 18:43:37 INFO - PID 31243 | Values: 1288.2 1322.9 1330.3 1334.1 1340.8 14245.4 14298.1 14918.3 14930.4 14935.9 15165.5 15469.9 15482.8 15489.8 15491.5 2668.2 2702.7 2702.7 2703.0 2703.7 2587.9 2660.5 2661.7 2662.2 2670.4 2628.7 2640.0 2656.2 2664.3 2668.2 2618.4 2646.1 2665.8 2673.9 2674.5 2662.3 2674.3 2677.9 2678.1 2684.7 13868.8 14020.9 14077.4 14081.0 14090.2 7458.4 7782.6 7785.0 7797.6 7800.1 5172.5 5246.6 5302.5 5386.6 5389.3 1874.7 1880.8 1898.2 1908.3 1939.8 24759.4 24984.4 25009.2 25058.9 25158.2 6205.9 6325.7 6387.8 6436.9 6444.5 16230.2 16241.9 16243.3 16247.4 16247.5 7482.6 7626.4 7643.4 7643.6 7643.8 5874.5 6029.5 6276.8 7040.5 7091.7 4806.8 4874.3 4928.8 4951.0 5015.3 1359.8 1370.3 1382.7 1395.9 1399.4 870.0 895.9 907.4 914.3 919.1 7614.7 7679.5 7695.6 7718.9 7770.1 690.5 713.9 716.8 717.3 721.7 5012.2 5897.2 5989.6 6013.3 6105.0 20053.0 20784.3 20811.2 20885.0 21255.5 20243.4 20269.4 20362.2 20604.3 20618.6 10738.1 10769.9 11182.7 11332.7 11338.4 8747.3 8891.4 8953.2 8999.7 9062.9 17244.0 17632.4 17875.2 17970.7 17981.3 17267.7 17754.9 18127.2 18167.2 18175.2 10424.8 11429.1 11699.8 11702.3 11707.1 13544.5 13661.9 13683.8 13693.3 13760.6 10606.9 10658.6 10708.9 10892.2 11181.9 10626.8 10803.4 10967.6 11007.1 11117.0 9738.2 9779.4 9852.9 9877.0 9882.2 6527.8 6592.2 6602.8 6607.5 6760.7 6723.0 6796.5 6808.1 6828.7 6895.3 18:43:37 INFO - PID 31243 | 18:43:37 INFO - PID 31243 | [#4] prototype.html Cycles:180 Average:5544.70 Median:6196.46 stddev:1562.79 (25.2%) stddev-sans-first:1520.65 18:43:37 INFO - PID 31243 | Values: 501.6 511.3 528.0 531.0 533.4 6370.0 6374.9 6380.3 6390.8 6413.0 6225.1 6271.8 6272.4 6295.6 6308.9 5121.0 5152.8 5191.0 5191.5 5222.5 4852.5 4960.5 5026.6 5157.1 5189.8 4903.5 4955.3 4965.8 4991.2 5007.5 4930.8 5001.5 5002.0 5015.3 5021.3 4334.7 4463.0 4465.0 4474.5 4486.6 6143.4 6147.6 6147.8 6154.6 6167.8 5695.2 5699.3 5699.9 5749.5 5752.7 6605.0 6607.3 6647.7 6665.6 6701.0 4889.4 4899.4 4903.1 4905.9 4911.2 6634.5 6665.4 6673.8 6796.9 6899.0 4791.7 4917.0 4988.9 4990.2 5013.5 5025.7 5036.9 5039.2 5052.2 5092.0 5655.0 5655.7 5707.2 5709.6 5736.6 6501.3 6563.5 6585.3 6591.9 6617.2 1748.5 1760.4 1769.9 1775.7 1781.7 1132.6 1199.5 1210.5 1211.8 1227.6 5835.9 5856.9 5886.3 5891.4 5898.9 6699.6 6703.2 6732.0 6745.9 6790.6 5780.7 5807.3 5823.6 5830.4 5895.5 3490.8 3498.6 3506.5 3517.4 3562.9 6508.6 6533.2 6544.9 6568.8 6636.3 6943.9 6964.3 6985.5 7002.8 7050.6 6023.6 6607.8 6615.9 6657.1 6668.4 6618.9 6619.6 6641.2 6739.8 6753.0 6662.9 6743.3 6748.8 6793.2 6797.9 6510.2 6584.1 6596.2 6623.5 6635.7 4908.2 4994.3 5013.3 5034.4 5046.3 6558.7 6670.8 6750.4 6864.4 6886.2 6490.5 6583.3 6597.7 6607.3 6653.7 6445.7 6492.5 6507.9 6508.7 6519.3 6500.5 6524.7 6546.2 6552.7 6566.1 6596.6 6649.7 6652.2 6661.3 6709.0 6369.8 6381.6 6400.6 6407.0 6417.5 18:43:37 INFO - PID 31243 | 18:43:37 INFO - PID 31243 | [#5] yui.html Cycles:180 Average:23941.24 Median:20845.24 stddev:33200.40 (159.3%) stddev-sans-first:33248.31 18:43:37 INFO - PID 31243 | Values: 863.7 870.9 885.4 888.5 892.9 16610.4 16976.5 16981.7 17095.3 19067.7 20217.9 20828.3 20835.0 20917.7 20941.0 931.6 933.6 939.7 940.5 942.0 901.6 935.4 935.9 937.9 938.1 1005.7 1005.9 1007.0 1007.3 1008.2 1004.3 1004.3 1004.4 1004.5 1004.5 729.1 736.8 736.8 738.5 739.0 15414.3 15524.7 15614.0 15651.7 16497.9 20579.8 21209.6 21256.0 21267.6 21277.1 15706.5 16006.6 16337.7 16383.3 16407.3 3670.1 3701.4 3711.1 3713.0 3714.1 125992.0 128919.7 129485.0 130267.3 132015.0 14188.0 14232.8 14573.8 14721.6 14722.3 29660.0 29715.8 30086.2 30111.7 30159.0 8179.5 8220.3 8393.7 8406.7 8415.4 4268.4 4361.6 4370.3 4372.2 4372.6 4959.8 5012.8 5053.4 5062.4 5106.6 885.9 908.2 914.4 917.1 917.1 659.7 671.2 675.2 679.9 683.6 11228.6 11242.2 11255.1 11681.1 12106.4 661.7 669.1 669.4 673.9 682.3 5105.7 5233.8 5313.5 5333.8 5422.8 111811.4 111869.4 112661.0 113765.0 113962.0 100358.0 103240.0 103498.5 105529.5 106648.0 83448.0 86046.6 86403.6 89072.0 89210.0 11779.7 12520.4 12536.0 12817.5 12980.4 38966.8 39258.8 39415.0 40535.8 41122.0 71441.6 71773.0 72499.8 73147.6 73707.6 18646.6 19435.5 19442.7 19656.6 19732.7 20184.8 20405.6 20855.5 20965.1 21058.0 18730.5 19305.7 19339.9 19366.0 19649.9 17133.1 17207.3 17264.1 18721.0 19638.8 18230.6 18424.3 18812.1 18941.3 19392.2 12037.6 12292.6 12491.3 12726.4 12755.4 13546.7 14051.9 14141.2 14260.4 14367.6 18:43:37 INFO - PID 31243 | -------- Summary: end -------- 18:43:37 INFO - PID 31243 | 18:43:37 INFO - TEST-INFO | 31243: exit 0 18:43:37 INFO - TEST-OK | dromaeo_css | took 1111724ms 18:43:37 INFO - TEST-START | kraken 18:43:37 INFO - Initialising browser for kraken test... 18:43:37 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53315/getInfo.html -profile /tmp/tmpVGFhVS/profile 18:43:37 INFO - TEST-INFO | started process 31616 (/builds/slave/test/build/application/firefox/firefox http://localhost:53315/getInfo.html) 18:43:44 INFO - TEST-INFO | 31616: exit 0 18:43:44 INFO - Browser initialized. 18:43:44 INFO - Running cycle 1/1 for kraken test... 18:43:44 INFO - Using env: {'DISPLAY': ':0', 18:43:44 INFO - 'HOME': '/home/cltbld', 18:43:44 INFO - 'JSGC_DISABLE_POISONING': '1', 18:43:44 INFO - 'LANG': 'en_US.UTF-8', 18:43:44 INFO - 'LANGUAGE': 'en_US:en', 18:43:44 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:43:44 INFO - 'LOGNAME': 'cltbld', 18:43:44 INFO - 'MAIL': '/var/mail/cltbld', 18:43:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:43:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:43:44 INFO - 'MOZ_CRASHREPORTER': '1', 18:43:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:43:44 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:43:44 INFO - 'MOZ_NO_REMOTE': '1', 18:43:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:43:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:43:44 INFO - 'NO_EM_RESTART': '1', 18:43:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:43:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:43:44 INFO - 'PWD': '/builds/slave/test', 18:43:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:43:44 INFO - 'RUST_BACKTRACE': 'full', 18:43:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:43:44 INFO - 'SHELL': '/bin/bash', 18:43:44 INFO - 'SHLVL': '1', 18:43:44 INFO - 'STYLO_FORCE_ENABLED': '1', 18:43:44 INFO - 'TERM': 'linux', 18:43:44 INFO - 'TMOUT': '86400', 18:43:44 INFO - 'USER': 'cltbld', 18:43:44 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453', 18:43:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:43:44 INFO - '_': '/tools/buildbot/bin/python'} 18:43:44 INFO - TEST-INFO | started process 31803 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVGFhVS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:43:45 INFO - PID 31803 | 18:43:45 INFO - PID 31803 | (/builds/slave/test/build/application/firefox/firefox:31852): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:43:45 INFO - PID 31803 | 18:43:55 INFO - PID 31803 | RSS: Main: 174002176 18:43:55 INFO - PID 31803 | 18:43:58 INFO - PID 31803 | Cycle 1(1): loaded http://localhost:53315/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53315/tests/kraken/driver.html?testName=audio-beat-detection) 18:43:59 INFO - PID 31803 | RSS: Main: 180068352 18:43:59 INFO - PID 31803 | 18:44:01 INFO - PID 31803 | Cycle 1(1): loaded http://localhost:53315/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53315/tests/kraken/driver.html?testName=audio-dft) 18:44:02 INFO - PID 31803 | RSS: Main: 180211712 18:44:02 INFO - PID 31803 | 18:44:05 INFO - PID 31803 | Cycle 1(1): loaded http://localhost:53315/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53315/tests/kraken/driver.html?testName=audio-fft) 18:44:06 INFO - PID 31803 | RSS: Main: 173785088 18:44:06 INFO - PID 31803 | 18:44:08 INFO - PID 31803 | Cycle 1(1): loaded http://localhost:53315/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53315/tests/kraken/driver.html?testName=audio-oscillator) 18:44:08 INFO - PID 31803 | RSS: Main: 174411776 18:44:08 INFO - PID 31803 | 18:44:11 INFO - PID 31803 | Cycle 1(1): loaded http://localhost:53315/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53315/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:44:11 INFO - PID 31803 | RSS: Main: 174559232 18:44:11 INFO - PID 31803 | 18:44:14 INFO - PID 31803 | Cycle 1(1): loaded http://localhost:53315/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53315/tests/kraken/driver.html?testName=imaging-darkroom) 18:44:15 INFO - PID 31803 | RSS: Main: 177242112 18:44:15 INFO - PID 31803 | 18:44:18 INFO - PID 31803 | Cycle 1(1): loaded http://localhost:53315/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53315/tests/kraken/driver.html?testName=imaging-desaturate) 18:44:19 INFO - PID 31803 | RSS: Main: 177516544 18:44:19 INFO - PID 31803 | 18:44:22 INFO - PID 31803 | Cycle 1(1): loaded http://localhost:53315/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53315/tests/kraken/driver.html?testName=json-parse-financial) 18:44:23 INFO - PID 31803 | RSS: Main: 177479680 18:44:23 INFO - PID 31803 | 18:44:24 INFO - PID 31803 | Cycle 1(1): loaded http://localhost:53315/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53315/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:44:25 INFO - PID 31803 | RSS: Main: 178044928 18:44:25 INFO - PID 31803 | 18:44:26 INFO - PID 31803 | Cycle 1(1): loaded http://localhost:53315/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53315/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:44:27 INFO - PID 31803 | RSS: Main: 177778688 18:44:27 INFO - PID 31803 | 18:44:29 INFO - PID 31803 | Cycle 1(1): loaded http://localhost:53315/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53315/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:44:30 INFO - PID 31803 | RSS: Main: 174985216 18:44:30 INFO - PID 31803 | 18:44:32 INFO - PID 31803 | Cycle 1(1): loaded http://localhost:53315/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53315/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:44:33 INFO - PID 31803 | RSS: Main: 174784512 18:44:33 INFO - PID 31803 | 18:44:36 INFO - PID 31803 | Cycle 1(1): loaded http://localhost:53315/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53315/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:44:37 INFO - PID 31803 | RSS: Main: 175652864 18:44:37 INFO - PID 31803 | 18:44:39 INFO - PID 31803 | Cycle 1(1): loaded http://localhost:53315/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53315/tests/kraken/driver.html?testName=ai-astar) 18:44:39 INFO - PID 31803 | RSS: Main: 176648192 18:44:39 INFO - PID 31803 | 18:44:39 INFO - PID 31803 | __start_tp_report 18:44:39 INFO - PID 31803 | _x_x_mozilla_page_load 18:44:39 INFO - PID 31803 | _x_x_mozilla_page_load_details 18:44:39 INFO - PID 31803 | |i|pagename|runs| 18:44:39 INFO - PID 31803 | |0;ai-astar;98;107;99;106;106;110;106;98;106;109 18:44:39 INFO - PID 31803 | |1;audio-beat-detection;139;117;153;179;117;148;149;117;176;128 18:44:39 INFO - PID 31803 | |2;audio-dft;162;163;163;163;167;163;167;165;167;163 18:44:39 INFO - PID 31803 | |3;audio-fft;73;74;75;179;77;74;75;86;98;73 18:44:39 INFO - PID 31803 | |4;audio-oscillator;79;79;129;79;79;78;131;79;78;128 18:44:39 INFO - PID 31803 | |5;imaging-gaussian-blur;113;114;114;114;113;113;114;114;114;114 18:44:39 INFO - PID 31803 | |6;imaging-darkroom;150;148;147;147;147;147;148;148;147;147 18:44:39 INFO - PID 31803 | |7;imaging-desaturate;94;94;94;93;93;94;94;94;94;94 18:44:39 INFO - PID 31803 | |8;json-parse-financial;52;51;51;52;52;51;51;50;52;52 18:44:39 INFO - PID 31803 | |9;json-stringify-tinderbox;38;38;38;38;38;37;38;42;38;38 18:44:39 INFO - PID 31803 | |10;stanford-crypto-aes;73;75;75;79;75;76;74;72;73;74 18:44:39 INFO - PID 31803 | |11;stanford-crypto-ccm;117;117;115;114;123;125;120;127;115;163 18:44:39 INFO - PID 31803 | |12;stanford-crypto-pbkdf2;171;172;174;170;181;171;171;171;169;169 18:44:39 INFO - PID 31803 | |13;stanford-crypto-sha256-iterative;67;63;63;62;63;62;68;64;66;63 18:44:39 INFO - PID 31803 | __end_tp_report 18:44:39 INFO - PID 31803 | __start_cc_report 18:44:39 INFO - PID 31803 | _x_x_mozilla_cycle_collect,948 18:44:39 INFO - PID 31803 | __end_cc_report 18:44:39 INFO - PID 31803 | __startTimestamp1503971079284__endTimestamp 18:44:39 INFO - PID 31803 | 18:44:39 INFO - PID 31803 | ------- Summary: start ------- 18:44:39 INFO - PID 31803 | Number of tests: 14 18:44:39 INFO - PID 31803 | 18:44:39 INFO - PID 31803 | [#0] ai-astar Cycles:10 Average:104.50 Median:109.50 stddev:4.48 (4.1%) stddev-sans-first:4.09 18:44:39 INFO - PID 31803 | Values: 98.0 107.0 99.0 106.0 106.0 110.0 106.0 98.0 106.0 109.0 18:44:39 INFO - PID 31803 | 18:44:39 INFO - PID 31803 | [#1] audio-beat-detection Cycles:10 Average:142.30 Median:148.50 stddev:23.12 (15.6%) stddev-sans-first:24.49 18:44:39 INFO - PID 31803 | Values: 139.0 117.0 153.0 179.0 117.0 148.0 149.0 117.0 176.0 128.0 18:44:39 INFO - PID 31803 | 18:44:39 INFO - PID 31803 | [#2] audio-dft Cycles:10 Average:164.30 Median:164.00 stddev:2.00 (1.2%) stddev-sans-first:1.94 18:44:39 INFO - PID 31803 | Values: 162.0 163.0 163.0 163.0 167.0 163.0 167.0 165.0 167.0 163.0 18:44:39 INFO - PID 31803 | 18:44:39 INFO - PID 31803 | [#3] audio-fft Cycles:10 Average:88.40 Median:75.00 stddev:32.80 (43.7%) stddev-sans-first:34.32 18:44:39 INFO - PID 31803 | Values: 73.0 74.0 75.0 179.0 77.0 74.0 75.0 86.0 98.0 73.0 18:44:39 INFO - PID 31803 | 18:44:39 INFO - PID 31803 | [#4] audio-oscillator Cycles:10 Average:93.90 Median:79.00 stddev:24.47 (31.0%) stddev-sans-first:25.35 18:44:39 INFO - PID 31803 | Values: 79.0 79.0 129.0 79.0 79.0 78.0 131.0 79.0 78.0 128.0 18:44:39 INFO - PID 31803 | 18:44:39 INFO - PID 31803 | [#5] imaging-gaussian-blur Cycles:10 Average:113.70 Median:114.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 18:44:39 INFO - PID 31803 | Values: 113.0 114.0 114.0 114.0 113.0 113.0 114.0 114.0 114.0 114.0 18:44:39 INFO - PID 31803 | 18:44:39 INFO - PID 31803 | [#6] imaging-darkroom Cycles:10 Average:147.60 Median:147.50 stddev:0.97 (0.7%) stddev-sans-first:0.50 18:44:39 INFO - PID 31803 | Values: 150.0 148.0 147.0 147.0 147.0 147.0 148.0 148.0 147.0 147.0 18:44:39 INFO - PID 31803 | 18:44:39 INFO - PID 31803 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:44:39 INFO - PID 31803 | Values: 94.0 94.0 94.0 93.0 93.0 94.0 94.0 94.0 94.0 94.0 18:44:39 INFO - PID 31803 | 18:44:39 INFO - PID 31803 | [#8] json-parse-financial Cycles:10 Average:51.40 Median:52.00 stddev:0.70 (1.3%) stddev-sans-first:0.71 18:44:39 INFO - PID 31803 | Values: 52.0 51.0 51.0 52.0 52.0 51.0 51.0 50.0 52.0 52.0 18:44:39 INFO - PID 31803 | 18:44:39 INFO - PID 31803 | [#9] json-stringify-tinderbox Cycles:10 Average:38.30 Median:38.00 stddev:1.34 (3.5%) stddev-sans-first:1.41 18:44:39 INFO - PID 31803 | Values: 38.0 38.0 38.0 38.0 38.0 37.0 38.0 42.0 38.0 38.0 18:44:39 INFO - PID 31803 | 18:44:39 INFO - PID 31803 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:75.00 stddev:1.96 (2.6%) stddev-sans-first:1.99 18:44:39 INFO - PID 31803 | Values: 73.0 75.0 75.0 79.0 75.0 76.0 74.0 72.0 73.0 74.0 18:44:39 INFO - PID 31803 | 18:44:39 INFO - PID 31803 | [#11] stanford-crypto-ccm Cycles:10 Average:123.60 Median:121.50 stddev:14.55 (12.0%) stddev-sans-first:15.24 18:44:39 INFO - PID 31803 | Values: 117.0 117.0 115.0 114.0 123.0 125.0 120.0 127.0 115.0 163.0 18:44:39 INFO - PID 31803 | 18:44:39 INFO - PID 31803 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:171.00 stddev:3.51 (2.1%) stddev-sans-first:3.71 18:44:39 INFO - PID 31803 | Values: 171.0 172.0 174.0 170.0 181.0 171.0 171.0 171.0 169.0 169.0 18:44:39 INFO - PID 31803 | 18:44:39 INFO - PID 31803 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:63.50 stddev:2.13 (3.4%) stddev-sans-first:1.99 18:44:39 INFO - PID 31803 | Values: 67.0 63.0 63.0 62.0 63.0 62.0 68.0 64.0 66.0 63.0 18:44:39 INFO - PID 31803 | -------- Summary: end -------- 18:44:39 INFO - PID 31803 | 18:44:39 INFO - PID 31803 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:44:39 INFO - TEST-INFO | 31803: exit 0 18:44:39 INFO - TEST-OK | kraken | took 62157ms 18:44:39 INFO - SUITE-END | took 1173s 18:44:40 INFO - Completed test suite (00:19:34) 18:44:40 INFO - javascript benchmark 18:44:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8448.114695105936, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3323.1193733660584, 3330.1175188575025, 3340.6492318306587, 3365.6634336566362, 3368.1242876852025, 6177.722002509887, 6224.315325314212, 6262.718177682003, 6289.591176573525, 6327.873442531147, 6118.357572454895, 6235.438810507054, 6238.344973777756, 6257.280412752523, 6268.435840774329, 5689.089745640698, 5703.31560212774, 5723.084306510959, 5782.855428614276, 5848.035074212749, 5811.883762324751, 5826.9708651456895, 5829.417058294158, 5840.532757379399, 5850.502707269887, 5568.415316391761, 5686.886262274731, 5740.167675687023, 5853.707314634251, 5860.267466566679, 5704.914426283609, 5767.077267637158, 5773.855653608652, 5815.040518314473, 5836.533077353802, 5671.092625179951, 5727.3699893011735, 5738.913916291259, 5802.477777000048, 5802.912956305659, 6106.297775755756, 6169.0, 6178.9691051544905, 6201.658908760016, 6252.030935205051, 6670.0, 6750.257471678112, 6754.0, 6758.189017317954, 6763.797086087426, 6749.0, 6751.831204219885, 6833.282505336918, 6843.555168914055, 6845.281245469254, 6246.281677607042, 6298.6535740534255, 6325.082862984841, 6329.019002054735, 6364.236291645032, 6927.203371612329, 6968.233494315621, 6975.476839237078, 6996.0, 7029.859402811915, 6548.508861835381, 6581.111549940792, 6637.336266373324, 6682.665866706645, 6690.431313338421, 6672.866542669217, 6733.158355205599, 6733.158355205599, 6739.292374300726, 6742.157230346207, 6638.701258443381, 6697.7655782047395, 6704.932950670528, 6730.0, 6731.966340168366, 6648.434883034996, 6678.7662431815625, 6752.291009444036, 6772.762953296611, 6781.8643627127185, 4882.584980276716, 4941.629377796679, 4949.034938187091, 4966.776495057731, 5014.548690617862, 4393.253146965024, 4396.296592545177, 4407.603315701603, 4441.844535441244, 4469.620082293041, 5746.856328591819, 5834.682340049329, 5897.764089436375, 5908.940910590925, 5926.7925622603, 6530.05314229434, 6636.601803891782, 6663.566868153554, 6743.8988415172835, 6749.6962636681465, 5799.33307669615, 5802.738876750556, 5821.359650438449, 5826.475617194473, 5829.795957141479, 5789.305283366023, 5818.069108942549, 5820.35976042635, 5820.94179058204, 5823.970880145572, 6529.706163222666, 6535.477161827042, 6564.6389448580785, 6628.138342015507, 6632.071509988606, 6571.047198156336, 6650.168728908887, 6659.633720145244, 6722.1261236040955, 6731.46148308144, 6556.442702370352, 6603.042558829039, 6629.370209830058, 6635.170603674541, 6683.8997415039785, 6616.5368424211765, 6629.171353580802, 6669.166354205724, 6737.090492783412, 6754.763583274562, 6719.664016799238, 6735.932640673531, 6746.359095885883, 6803.285655006351, 6866.656667166521, 6536.705848236746, 6567.835804104744, 6608.339166083546, 6611.30581288977, 6620.3710647488415, 6504.577202481823, 6527.0, 6589.934100658932, 6613.107230523818, 6625.867482650224, 6363.172787537776, 6442.935570644234, 6443.065755465525, 6445.097686323825, 6445.70994305248, 6489.221293444817, 6496.707648155939, 6514.706838192198, 6522.902156467562, 6531.444827189742, 6293.150424692608, 6301.306856245901, 6313.873722525615, 6329.588576742497, 6408.615483070844], "value": 6118.762253699418, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.4710117771682, 843.1231519220012, 876.8947726272849, 880.0187790613464, 1122.4780477078168, 30454.0, 31114.53328200079, 41831.581684183184, 42543.787281063625, 42641.78679106608, 42805.35791963123, 43222.0, 43228.35157472632, 43261.13477730443, 43287.0, 7757.806054848632, 7929.048514178277, 7940.920590794105, 7943.404244681672, 7944.0, 7799.142094369615, 7919.722809701661, 7925.920740792576, 7928.048634163908, 7930.6431210595665, 7898.723544675938, 7934.7222847200355, 7938.126806051329, 7938.24586664266, 7938.364930805551, 7879.290863822255, 7913.604319784016, 7915.564643944581, 7917.0895347034675, 7919.049714034324, 7796.298333149987, 7841.137474877759, 7847.058352997619, 7849.136594974549, 7855.7250496232355, 37331.25337493235, 37363.626363736556, 37381.25237495262, 37449.43825842615, 37605.811970940245, 3471.3230919970615, 3493.7729047611824, 3511.8244087795765, 3537.5047493350694, 3540.7698499597445, 30983.38033239345, 31076.37847243065, 31089.84455077733, 31144.0, 31160.220994475094, 5077.136886729228, 5112.744362781883, 5137.383513978347, 5161.741912904377, 5164.715940623264, 182196.08901955537, 182276.0, 182606.0, 183318.0, 183404.0, 47265.0, 47313.763431182626, 47433.762831185624, 47463.52536474591, 47506.76246618745, 49863.50136498661, 49890.0, 49911.25133123006, 49955.50044499509, 50011.24983125256, 13660.590182294547, 14138.363773630212, 14629.414823407158, 14910.850891491164, 14916.477923272634, 12484.063695222894, 12533.122681412397, 12562.87437125617, 12606.936965315299, 12649.367531623566, 13789.0, 13835.10071845347, 13862.168269903837, 13963.930180349236, 14097.0, 5497.505224529811, 5592.0, 5686.630369026, 5728.77084916607, 5784.88430231393, 977.310995747996, 1004.8643433136433, 1008.1330056151704, 1015.5633077776627, 1032.6179313653845, 22931.197408090637, 28230.85884570605, 29010.564841527394, 29150.0, 29239.122826315244, 1081.1837063017372, 1082.9945850270856, 1086.521930350659, 1090.432974853072, 1094.0591091661165, 12149.331786751636, 12274.877251227552, 12343.876561234452, 12387.876121238853, 12447.190932589352, 241124.0, 241800.0, 242181.0, 242203.0, 242477.0, 52557.0, 52953.73523132437, 53010.73494632502, 53047.73476132595, 53179.468205318226, 1379.5585412668052, 1425.7504936636046, 1437.4106616287272, 1445.147363055802, 1447.232966527742, 22407.77592224122, 22477.887610561844, 22613.773862261165, 22798.430039249153, 23036.308910732703, 36899.26201476009, 37269.813650931574, 37319.44020839744, 37331.626683732815, 37347.81326093352, 215496.0, 217925.0, 218452.0, 218629.0, 218953.0, 29399.70600294055, 30084.398312034074, 32714.672853271164, 32809.835950820096, 32857.67142328642, 42115.57884421239, 42163.36754948617, 42263.7886810564, 42567.14865702607, 42604.78697606616, 31649.367012660077, 31700.207494812814, 31833.204169895012, 31978.0, 32190.0, 31124.221894451915, 31216.53175202419, 31258.843705781324, 31508.0, 32085.67914320827, 35174.824125879204, 35186.296274074884, 35305.82347088248, 35356.469652954715, 35374.0, 29942.550861737527, 30020.0, 30189.849050754605, 30396.696033039385, 30440.391192176474, 22928.54142917099, 23232.3030309091, 23441.062357505507, 23546.058157673502, 23709.64435533503], "value": 17103.81981565528, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [549.6784381137035, 549.8570371703358, 556.7605929450335, 567.4382361462153, 571.9771209151634, 21394.786052139494, 22172.0, 22204.333869983908, 22223.888880555576, 22244.666330005024, 23767.524649507042, 25691.229263122135, 25743.0989915353, 25750.613740793902, 25753.96984120633, 6553.803385898419, 6679.231888332855, 6682.331766823328, 6684.83287917801, 6685.699143538553, 6068.27180738312, 6551.737930482775, 6661.0, 6676.599404035749, 6681.198256209262, 6640.833979150536, 6648.102506161679, 6655.134832471772, 6657.667116644173, 6658.267590565034, 6623.503237257225, 6627.204735431731, 6643.501737369693, 6645.169353830771, 6656.167979002625, 6134.0, 6135.9693201534155, 6144.723487443076, 6151.569390142692, 6151.907721384183, 28489.145325640056, 28881.0, 28997.0, 29145.854270728723, 29410.11769646914, 2014.6977953306975, 2054.6404379233572, 2056.4447599148148, 2059.3101311060664, 2061.371281759063, 18878.528036799053, 19367.0, 19367.51581210481, 19421.22315107394, 19425.4172374829, 2335.1476711000532, 2344.1443872986238, 2388.820838437124, 2391.892364843586, 2421.806255499556, 130577.34711326478, 133163.33418332943, 134501.0, 134594.0, 136989.3150534241, 14502.347394367278, 14682.265886705622, 14836.0, 15136.772948405784, 15369.923150384177, 22243.0, 22741.772582274298, 22914.65628015581, 22927.197548085736, 22953.655695164587, 9798.363106398061, 9899.059589339002, 9998.600055997678, 10017.198624110053, 10079.143272821892, 9095.31785116119, 9107.22588579978, 9112.8633070504, 9214.539273036322, 9228.7692807679, 9187.724368268962, 9224.0, 9298.72103836886, 9421.481818499911, 9422.481763502936, 2656.296081538394, 2689.3411114276737, 2716.3480764616743, 2726.631904692841, 2773.309889407272, 950.9476978766235, 953.6423841059602, 964.1708131007329, 974.6539978307741, 983.1151963233004, 19475.610487790163, 19791.703124453146, 19974.800251997294, 20162.89918550398, 23330.183443579393, 898.6585097662846, 985.2265971212564, 1021.5403068619152, 1027.0653705128448, 1027.9229057820694, 10592.629257975934, 10604.89395106039, 10809.135269178447, 10854.891177100695, 10857.891421085846, 506136.0, 515898.0, 523441.0, 532553.0, 540976.0, 116305.0, 116565.4171729136, 117148.41425792816, 118259.0, 119514.0, 1197.2722844617633, 1213.5206593395735, 1239.3988915376035, 1241.453760345445, 1251.69959209789, 16886.915565422587, 17193.57016074608, 17649.38227162043, 17669.0, 17880.0, 50436.991260173854, 50699.746501267255, 53114.0, 54572.45427545832, 57146.42853571411, 69293.65353173202, 69337.0, 69615.65192174006, 70011.29988700252, 70518.0, 14434.0, 14468.27658617044, 14530.346134424188, 14536.41854325815, 14604.342804573607, 22887.771122288563, 22890.65664015008, 22984.885075574515, 23035.308940731804, 23066.769332306463, 17934.282628694706, 18121.90939045349, 18126.5468363292, 18219.908900455943, 18241.0, 16337.754933675767, 16391.0, 16509.58726031859, 17126.914365428092, 17287.135643217538, 17546.561335966704, 18563.164657590172, 18740.0, 18742.0, 18777.061146942873, 14398.496052638107, 14427.99004069747, 14476.855231447551, 14490.058146220463, 14637.121772693672, 17811.931284122995, 17837.286508539513, 17872.642547149244, 18146.18342174632, 18175.81824181741], "value": 11904.204036652598, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1288.1755676367125, 1322.927239001855, 1330.275000124933, 1334.0728193905234, 1340.7653660609396, 14245.358958846851, 14298.142111473293, 14918.25408729562, 14930.402783888645, 14935.925320373397, 15165.469208577702, 15469.922650386761, 15482.767758483631, 15489.767653485207, 15491.457798977039, 2668.159529748128, 2702.702702702701, 2702.729727027301, 2702.9864850675817, 2703.7431444012786, 2587.935301617456, 2660.5344064788683, 2661.6672915885515, 2662.214646679234, 2670.3724624713177, 2628.6582744243224, 2639.986800065997, 2656.150031989764, 2664.307280107176, 2668.1862032080207, 2618.410857557044, 2646.087099950514, 2665.7734092602054, 2673.866306684658, 2674.4784766970447, 2662.3077999720017, 2674.251209661298, 2677.8794954227105, 2678.0760637580197, 2684.6912605050475, 13868.791968120486, 14020.92989535056, 14077.366518506691, 14081.0, 14090.225037622926, 7458.4033277337685, 7782.571958542278, 7785.026871641045, 7797.610119494003, 7800.141984381714, 5172.482751724798, 5246.580273578104, 5302.522772950414, 5386.649867758583, 5389.326334208224, 1874.7281644161656, 1880.8401285890716, 1898.211017299415, 1908.2557802457052, 1939.8254157125784, 24759.381015474577, 24984.375390615198, 25009.249722508353, 25058.87470562654, 25158.245252642268, 6205.937940620536, 6325.683715814191, 6387.808365749035, 6436.903446448307, 6444.516661250331, 16230.188490575423, 16241.918790405973, 16243.269052892649, 16247.431339903047, 16247.51257462278, 7482.551046937201, 7626.389888808882, 7643.388528917673, 7643.617819109022, 7643.847123057508, 5874.471297583238, 6029.487493563009, 6276.843078923063, 7040.471964602675, 7091.680874360666, 4806.759662016885, 4874.268859671007, 4928.827491037868, 4951.0, 5015.269731942547, 1359.7960305954186, 1370.2532119994628, 1382.7372799168327, 1395.8813500852339, 1399.3632897031825, 869.9739007829776, 895.9462432254086, 907.3693782820876, 914.3005600715325, 919.0579655852804, 7614.6954121835615, 7679.500832445873, 7695.615219239127, 7718.884216736754, 7770.067591889009, 690.4683393786756, 713.9107611548557, 716.7885473785246, 717.3006318839194, 721.7401735375261, 5012.223105418666, 5897.2038774765715, 5989.610675306179, 6013.308469525973, 6104.969475152596, 20053.0, 20784.27255046066, 20811.1675532977, 20885.0, 21255.468613284484, 20243.39269821908, 20269.391918242476, 20362.18551257933, 20604.27885024017, 20618.58762824765, 10738.087262582769, 10769.9461502692, 11182.66452006441, 11332.660020199408, 11338.43307834605, 8747.300215982834, 8891.377603567687, 8953.194212520904, 8999.685011024845, 9062.909370906207, 17244.0, 17632.44329572179, 17875.19561619704, 17970.730439043164, 17981.280748769903, 17267.65464690674, 17754.91122544379, 18127.184276707845, 18167.18247678884, 18175.18211680451, 10424.843627345444, 11429.142814289127, 11699.824502632297, 11702.2978621283, 11707.063434925016, 13544.458221671417, 13661.863381366456, 13683.794743078663, 13693.315334233448, 13760.587182384545, 10606.893931060591, 10658.62694805678, 10708.946455267675, 10892.183086268404, 11181.888181118085, 10626.787464250827, 10803.405812680377, 10967.616133435291, 11007.119430445386, 11117.0, 9738.172255358373, 9779.364341317792, 9852.901470985198, 9876.950615246878, 9882.209423246266, 6527.836804079936, 6592.24189218246, 6602.8349291268105, 6607.5044371673275, 6760.661966901734, 6722.9663851680425, 6796.490263230178, 6808.080909077409, 6828.658567071528, 6895.310468953065], "value": 6420.043776427966, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.5728512822, 511.2714382005642, 527.965682230655, 531.0494215354515, 533.4425525326034, 6370.0, 6374.872502549946, 6380.266269379029, 6390.776322828702, 6413.0, 6225.097360882669, 6271.811845644626, 6272.435480806726, 6295.559310848231, 6308.873822523558, 5120.974395128019, 5152.793888244466, 5191.0, 5191.506806853352, 5222.451642577532, 4852.539008794159, 4960.479149689285, 5026.642806442258, 5157.149070403379, 5189.844304670866, 4903.455411545369, 4955.256711493287, 4965.776540055706, 4991.201407774757, 5007.474215207405, 4930.753462326905, 5001.549860512535, 5001.974595207992, 5015.322931404268, 5021.322121513585, 4334.739915605074, 4463.040446304028, 4464.977675111636, 4474.507804141542, 4486.55134486552, 6143.447089761943, 6147.600405973598, 6147.815565533041, 6154.599951003171, 6167.814965551041, 5695.202671625976, 5699.344575373843, 5699.914501282443, 5749.482546570829, 5752.654840709529, 6604.966975165142, 6607.315134640684, 6647.734090636369, 6665.633390163539, 6700.966495167493, 4889.437714662824, 4899.436564795059, 4903.092927808331, 4905.8773530661665, 4911.214205727102, 6634.502412319095, 6665.433623098568, 6673.79657764271, 6796.898046529355, 6899.0, 4791.736454494967, 4916.975415122902, 4988.850334489971, 4990.17662085759, 5013.473585273567, 5025.723585202849, 5036.874078148076, 5039.168537191323, 5052.191649336089, 5091.963214714144, 5654.97172514143, 5655.660660360391, 5707.229524014288, 5709.571782116358, 5736.627119237236, 6501.31736167705, 6563.540552161303, 6585.3085426029575, 6591.868162636721, 6617.172853393326, 1748.5453782016514, 1760.4102625620305, 1769.8584113270908, 1775.7392251501578, 1781.714925611896, 1132.6262333429947, 1199.4542483170312, 1210.5218438716659, 1211.7515909238584, 1227.5519435406013, 5835.883282334415, 5856.94143058564, 5886.293644762656, 5891.3814049525035, 5898.852528686732, 6699.631520266432, 6703.229128650268, 6732.004258105097, 6745.898811517831, 6790.5925644461495, 5780.653160810365, 5807.332156801937, 5823.592348535646, 5830.425785038016, 5895.528357731371, 3490.7782276203125, 3498.5801703795705, 3506.4915587239707, 3517.419625761772, 3562.857485700543, 6508.60948343101, 6533.18335208099, 6544.869102618016, 6568.751937132025, 6636.30318816527, 6943.930560694429, 6964.26875178099, 6985.476089293324, 7002.824929376807, 7050.5769653820935, 6023.5783495156675, 6607.819419591523, 6615.933840661628, 6657.101291325609, 6668.433183179387, 6618.90071648916, 6619.635920024445, 6641.236257830411, 6739.797806065826, 6752.966235168793, 6662.866742665216, 6743.2582415935185, 6748.797536073926, 6793.150856142982, 6797.9320206799275, 6510.153680021567, 6584.144061272004, 6596.208454985433, 6623.503237257321, 6635.668216589054, 4908.233036106927, 4994.275830004702, 5013.323201367914, 5034.395872495324, 5046.318746969112, 6558.704858281484, 6670.83322916931, 6750.426213771884, 6864.38220560152, 6886.208086070166, 6490.513211509061, 6583.34166583357, 6597.703103360264, 6607.306232845482, 6653.700583473658, 6445.709943052668, 6492.48060155177, 6507.869842603216, 6508.739650413931, 6519.282878883224, 6500.497308536342, 6524.706388212637, 6546.18172728409, 6552.737890484327, 6566.146400968035, 6596.637184954873, 6649.73401063952, 6652.201735791736, 6661.300563440963, 6708.966455167693, 6369.808905732835, 6381.649009304533, 6400.615963042233, 6407.0, 6417.454516365974], "value": 5083.372426526182, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [863.6516177428589, 870.9085546017668, 885.3890815337417, 888.4935586715575, 892.9196372326492, 16610.41863534775, 16976.490705278848, 16981.660366792672, 17095.31618735249, 19067.713984290247, 20217.898910505464, 20828.271010514636, 20835.0, 20917.686234706493, 20941.0, 931.5994122527311, 933.551895090357, 939.7180845746249, 940.4780346907463, 941.9670311539097, 901.6123067081153, 935.3966691484022, 935.8830146231721, 937.8780758501385, 938.1275413865103, 1005.7234260578363, 1005.8893521712606, 1006.9748256293578, 1007.2646967713573, 1008.2488546033215, 1004.2618675273668, 1004.3321191407705, 1004.3772860826261, 1004.5027711282962, 1004.5278719002057, 729.1141263365037, 736.8047467421138, 736.8268456912622, 738.4535443771624, 739.0318682525908, 15414.306356213885, 15524.689506209925, 15614.0, 15651.686966260611, 16497.91751041249, 20579.79420205809, 21209.575808483743, 21256.0, 21267.5746485071, 21277.14891404342, 15706.450274240347, 16006.5998350041, 16337.673246535122, 16383.344666213337, 16407.261673224733, 3670.06413364593, 3701.3707669695946, 3711.1278849470355, 3712.962870371289, 3714.1457464783252, 125992.0, 128919.71080289171, 129485.0, 130267.34866325703, 132015.0, 14188.0, 14232.786508202385, 14573.781393279109, 14721.55835324942, 14722.337494812758, 29660.0, 29715.811367545488, 30086.24784380408, 30111.698883011326, 30159.0, 8179.468334558234, 8220.301274391624, 8393.748187554384, 8406.663733450594, 8415.368847336475, 4268.381084742695, 4361.607455328972, 4370.2570563004365, 4372.23485889968, 4372.584604462571, 4959.82640607577, 5012.7994880205115, 5053.418856831511, 5062.443131255559, 5106.617003724736, 885.8671199320154, 908.246155690775, 914.3736540469799, 917.1104029091771, 917.1470532404794, 659.7492952677952, 671.1912145864222, 675.1594265139933, 679.911611490513, 683.596677960006, 11228.606998755004, 11242.213045086766, 11255.099592032619, 11681.123915706356, 12106.394680265952, 661.7022339947043, 669.1100835888257, 669.4242951062083, 673.8921772516373, 682.3483573187594, 5105.693658380509, 5233.842984710464, 5313.521783039468, 5333.759980800795, 5422.755975981011, 111811.4409427964, 111869.44065279621, 112661.0, 113765.0, 113962.0, 100358.0, 103240.0, 103498.48250758697, 105529.47235263775, 106648.0, 83448.0, 86046.56976715202, 86403.56798215969, 89072.0, 89210.0, 11779.705507362385, 12520.436580353724, 12536.0, 12817.487300507875, 12980.391960853762, 38966.80516597399, 39258.803705981285, 39415.0, 40535.797321013204, 41122.0, 71441.6427917857, 71773.0, 72499.82500524992, 73147.63426183048, 73707.63146184235, 18646.627067458845, 19435.51411214731, 19442.708359374337, 19656.606867862847, 19732.704009440156, 20184.79815201829, 20405.591888161856, 20855.47861303419, 20965.05657245397, 21058.0, 18730.531736706693, 19305.710414343514, 19339.90330048341, 19366.0, 19649.901750491157, 17133.143342832558, 17207.31170753206, 17264.13679316004, 18721.0, 19638.803611964267, 18230.635387291914, 18424.263029478672, 18812.05939702982, 18941.337053203624, 19392.2243110274, 12037.578684745991, 12292.631221063382, 12491.312977786309, 12726.363681816058, 12755.362231888183, 13546.661333466347, 14051.929740351641, 14141.151530908179, 14260.358283877044, 14367.640808979859], "value": 8940.99352271214, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1472.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 107.0, 99.0, 106.0, 106.0, 110.0, 106.0, 98.0, 106.0, 109.0], "value": 104.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 117.0, 153.0, 179.0, 117.0, 148.0, 149.0, 117.0, 176.0, 128.0], "value": 142.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 163.0, 163.0, 167.0, 163.0, 167.0, 165.0, 167.0, 163.0], "value": 164.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 75.0, 179.0, 77.0, 74.0, 75.0, 86.0, 98.0, 73.0], "value": 88.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 129.0, 79.0, 79.0, 78.0, 131.0, 79.0, 78.0, 128.0], "value": 93.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 114.0, 113.0, 113.0, 114.0, 114.0, 114.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 148.0, 147.0, 147.0, 147.0, 147.0, 148.0, 148.0, 147.0, 147.0], "value": 147.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 51.0, 52.0, 52.0, 51.0, 51.0, 50.0, 52.0, 52.0], "value": 51.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 38.0, 38.0, 37.0, 38.0, 42.0, 38.0, 38.0], "value": 38.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 75.0, 79.0, 75.0, 76.0, 74.0, 72.0, 73.0, 74.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 115.0, 114.0, 123.0, 125.0, 120.0, 127.0, 115.0, 163.0], "value": 123.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 174.0, 170.0, 181.0, 171.0, 171.0, 171.0, 169.0, 169.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 63.0, 63.0, 62.0, 63.0, 62.0, 68.0, 64.0, 66.0, 63.0], "value": 64.1, "unit": "score"}]}]} 18:44:40 INFO - Return code: 0 18:44:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:44:40 INFO - # TBPL SUCCESS # 18:44:40 INFO - Running post-action listener: _package_coverage_data 18:44:40 INFO - Running post-action listener: _resource_record_post_action 18:44:40 INFO - [mozharness: 2017-08-29 01:44:40.589621Z] Finished run-tests step (success) 18:44:40 INFO - Running post-run listener: _resource_record_post_run 18:44:40 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 12288; Write bytes: 336216064; Read time: 428; Write time: 892460 18:44:40 INFO - TinderboxPrint: CPU usage
13.2% 18:44:40 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 428 18:44:40 INFO - TinderboxPrint: I/O write bytes / time
336,216,064 / 892,460 18:44:40 INFO - TinderboxPrint: CPU idle
8,251.4 (86.8%) 18:44:40 INFO - TinderboxPrint: CPU user
1,193.9 (12.6%) 18:44:40 INFO - TinderboxPrint: Swap in / out
0 / 0 18:44:40 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 14573568; Read time: 0; Write time: 8284 18:44:40 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:40 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 8192; Write bytes: 320610304; Read time: 380; Write time: 884116 18:44:41 INFO - Running post-run listener: _upload_blobber_files 18:44:41 INFO - Blob upload gear active. 18:44:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:44:41 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:41 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:41 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:41 INFO - (blobuploader) - INFO - Open directory for files ... 18:44:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:44:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:44:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:42 INFO - (blobuploader) - INFO - Done attempting. 18:44:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:44:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:44:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:46 INFO - (blobuploader) - INFO - Done attempting. 18:44:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:44:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:44:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:47 INFO - (blobuploader) - INFO - Done attempting. 18:44:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:44:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:44:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:48 INFO - (blobuploader) - INFO - Done attempting. 18:44:48 INFO - (blobuploader) - INFO - Iteration through files over. 18:44:48 INFO - Return code: 0 18:44:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:44:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:44:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/219a24517a6753cfbc88092c2abd35c868fea06427e0f314eff9bf7f667b1ba93809d59b534b2ff598f27b555d3c4947ca369350c9c397eab175fb25f5b04eb9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ea866affd6c8c1f4d756df08b3b06f419a06829285536431dee6c11bbe609a72b655077826a5284f229edd9d0f27f181402eb7c34b399c1456d0de316d363c4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e667ed0b6ad56386fab65eda1bdab0530026ee468f51567df37168ae60fa9d9393ca44e4a24a9ac030d5eeb1bd8842d4db8d924fafac4ca1dcfe68a79520f33", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/289184f071cf2619e54503682587d83f3525fd80c8b3414580d21275d5234c407424766b29e1ba55170b48b2122e428e29e369d3bf1ae3171e31b72eb18bded3"} 18:44:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:44:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:44:48 INFO - Contents: 18:44:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/219a24517a6753cfbc88092c2abd35c868fea06427e0f314eff9bf7f667b1ba93809d59b534b2ff598f27b555d3c4947ca369350c9c397eab175fb25f5b04eb9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ea866affd6c8c1f4d756df08b3b06f419a06829285536431dee6c11bbe609a72b655077826a5284f229edd9d0f27f181402eb7c34b399c1456d0de316d363c4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e667ed0b6ad56386fab65eda1bdab0530026ee468f51567df37168ae60fa9d9393ca44e4a24a9ac030d5eeb1bd8842d4db8d924fafac4ca1dcfe68a79520f33", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/289184f071cf2619e54503682587d83f3525fd80c8b3414580d21275d5234c407424766b29e1ba55170b48b2122e428e29e369d3bf1ae3171e31b72eb18bded3"} 18:44:48 INFO - Running post-run listener: copy_logs_to_upload_dir 18:44:48 INFO - Copying logs to upload dir... 18:44:48 INFO - mkdir: /builds/slave/test/build/upload/logs 18:44:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.997305 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-08-28 18:44:48.473080) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-28 18:44:48.477213) ========= 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=8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453 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/219a24517a6753cfbc88092c2abd35c868fea06427e0f314eff9bf7f667b1ba93809d59b534b2ff598f27b555d3c4947ca369350c9c397eab175fb25f5b04eb9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ea866affd6c8c1f4d756df08b3b06f419a06829285536431dee6c11bbe609a72b655077826a5284f229edd9d0f27f181402eb7c34b399c1456d0de316d363c4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e667ed0b6ad56386fab65eda1bdab0530026ee468f51567df37168ae60fa9d9393ca44e4a24a9ac030d5eeb1bd8842d4db8d924fafac4ca1dcfe68a79520f33", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/289184f071cf2619e54503682587d83f3525fd80c8b3414580d21275d5234c407424766b29e1ba55170b48b2122e428e29e369d3bf1ae3171e31b72eb18bded3"} build_url:https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011261 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/219a24517a6753cfbc88092c2abd35c868fea06427e0f314eff9bf7f667b1ba93809d59b534b2ff598f27b555d3c4947ca369350c9c397eab175fb25f5b04eb9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ea866affd6c8c1f4d756df08b3b06f419a06829285536431dee6c11bbe609a72b655077826a5284f229edd9d0f27f181402eb7c34b399c1456d0de316d363c4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e667ed0b6ad56386fab65eda1bdab0530026ee468f51567df37168ae60fa9d9393ca44e4a24a9ac030d5eeb1bd8842d4db8d924fafac4ca1dcfe68a79520f33", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/289184f071cf2619e54503682587d83f3525fd80c8b3414580d21275d5234c407424766b29e1ba55170b48b2122e428e29e369d3bf1ae3171e31b72eb18bded3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-28 18:44:48.506855) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:44:48.507142) ========= 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=8783db0840cc980e80bb0bd400000236-1503966852.816828-125374453 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004616 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:44:48.588479) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-28 18:44:48.588764) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-28 18:44:48.592064) ========= ========= Total master_lag: 0.24 =========