builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-049 starttime: 1504824516.73 results: success (0) revision: b4c1ad9565ee9d00d96501c4a83083daf25c1413 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-07 15:48:36.735202) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-07 15:48:36.735664) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-07 15:48:36.735940) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-07 15:48:36.754261) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 15:48:36.754629) ========= 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=db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-07 15:48:36-- 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% 39.0M=0s 2017-09-07 15:48:36 (39.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092351 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 15:48:36.863990) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 15:48:36.864315) ========= 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=db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018577 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 15:48:36.913954) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-07 15:48:36.914296) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b4c1ad9565ee9d00d96501c4a83083daf25c1413 --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 b4c1ad9565ee9d00d96501c4a83083daf25c1413 --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=db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-07 15:48:36,986 truncating revision to first 12 chars 2017-09-07 15:48:36,986 Setting DEBUG logging. 2017-09-07 15:48:36,987 attempt 1/10 2017-09-07 15:48:36,987 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b4c1ad9565ee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-07 15:48:38,220 unpacking tar archive at: mozilla-central-b4c1ad9565ee/testing/mozharness/ program finished with exit code 0 elapsedTime=1.529014 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-07 15:48:38.456864) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-07 15:48:38.457282) ========= script_repo_revision: b4c1ad9565ee9d00d96501c4a83083daf25c1413 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-07 15:48:38.457665) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-07 15:48:38.457929) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-07 15:48:38.470322) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-09-07 15:48:38.470653) ========= /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=db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:48:38 INFO - MultiFileLogger online at 20170907 15:48:38 in /builds/slave/test 15:48:38 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 15:48:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:48:38 INFO - {'append_to_log': False, 15:48:38 INFO - 'base_work_dir': '/builds/slave/test', 15:48:38 INFO - 'blob_upload_branch': 'Firefox', 15:48:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:48:38 INFO - 'branch': 'Firefox', 15:48:38 INFO - 'buildbot_json_path': 'buildprops.json', 15:48:38 INFO - 'code_coverage': False, 15:48:38 INFO - 'config_files': ('talos/linux_config.py',), 15:48:38 INFO - 'default_actions': ('clobber', 15:48:38 INFO - 'read-buildbot-config', 15:48:38 INFO - 'download-and-extract', 15:48:38 INFO - 'populate-webroot', 15:48:38 INFO - 'create-virtualenv', 15:48:38 INFO - 'install', 15:48:38 INFO - 'setup-mitmproxy', 15:48:38 INFO - 'run-tests'), 15:48:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:48:38 INFO - 'disable_ccov_upload': False, 15:48:38 INFO - 'disable_stylo': False, 15:48:38 INFO - 'download_minidump_stackwalk': True, 15:48:38 INFO - 'download_symbols': 'ondemand', 15:48:38 INFO - 'enable_stylo': False, 15:48:38 INFO - 'enable_webrender': False, 15:48:38 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:48:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:48:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:48:38 INFO - 'gecko_profile': False, 15:48:38 INFO - 'gecko_profile_interval': 0, 15:48:38 INFO - 'installer_path': 'installer.exe', 15:48:38 INFO - 'log_level': 'info', 15:48:38 INFO - 'log_name': 'talos', 15:48:38 INFO - 'log_to_console': True, 15:48:38 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:48:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:48:38 INFO - 'opt_config_files': (), 15:48:38 INFO - 'pip_index': False, 15:48:38 INFO - 'suite': 'dromaeojs-e10s', 15:48:38 INFO - 'system_bits': '32', 15:48:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:48:38 INFO - 'title': 'talos-linux64-ix-049', 15:48:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:48:38 INFO - 'use_talos_json': True, 15:48:38 INFO - 'verify': 'False', 15:48:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:48:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:48:38 INFO - 'work_dir': 'build'} 15:48:38 INFO - [mozharness: 2017-09-07 22:48:38.684479Z] Running clobber step. 15:48:38 INFO - Running pre-action listener: _resource_record_pre_action 15:48:38 INFO - Running main action method: clobber 15:48:38 INFO - rmtree: /builds/slave/test/build 15:48:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:48:39 INFO - Running post-action listener: _resource_record_post_action 15:48:39 INFO - [mozharness: 2017-09-07 22:48:39.720820Z] Finished clobber step (success) 15:48:39 INFO - [mozharness: 2017-09-07 22:48:39.720916Z] Running read-buildbot-config step. 15:48:39 INFO - Running pre-action listener: _resource_record_pre_action 15:48:39 INFO - Running main action method: read_buildbot_config 15:48:39 INFO - Using buildbot properties: 15:48:39 INFO - { 15:48:39 INFO - "project": "", 15:48:39 INFO - "product": "firefox", 15:48:39 INFO - "who": "archaeopteryx@coole-files.de", 15:48:39 INFO - "installer_path": "public/build/target.tar.bz2", 15:48:39 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 15:48:39 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:48:39 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 15:48:39 INFO - "stage_platform": "linux64-qr", 15:48:39 INFO - "basedir": "/builds/slave/test", 15:48:39 INFO - "buildnumber": 37, 15:48:39 INFO - "platform": "ubuntu64_hw_qr", 15:48:39 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:48:39 INFO - "slavebuilddir": "test", 15:48:39 INFO - "taskId": "AZwHXuMOTduBRZL_SELqhA", 15:48:39 INFO - "branch": "mozilla-central", 15:48:39 INFO - "script_repo_revision": "production", 15:48:39 INFO - "revision": "b4c1ad9565ee9d00d96501c4a83083daf25c1413", 15:48:39 INFO - "slavename": "talos-linux64-ix-049", 15:48:39 INFO - "repo_path": "mozilla-central" 15:48:39 INFO - } 15:48:39 INFO - Finding installer, test and symbols from parent task. 15:48:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AZwHXuMOTduBRZL_SELqhA'}, attempt #1 15:48:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AZwHXuMOTduBRZL_SELqhA'}, attempt #1 15:48:40 INFO - Task dependencies: ZsNuXBl0Q92uv17nTmcSug 15:48:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug'}, attempt #1 15:48:41 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.tar.bz2 15:48:41 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.test_packages.json 15:48:41 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.crashreporter-symbols.zip 15:48:41 INFO - Running post-action listener: _resource_record_post_action 15:48:41 INFO - [mozharness: 2017-09-07 22:48:41.051134Z] Finished read-buildbot-config step (success) 15:48:41 INFO - [mozharness: 2017-09-07 22:48:41.051353Z] Running download-and-extract step. 15:48:41 INFO - Running pre-action listener: _resource_record_pre_action 15:48:41 INFO - Running main action method: download_and_extract 15:48:41 INFO - mkdir: /builds/slave/test/build/tests 15:48: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')]} 15:48:41 INFO - trying https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.test_packages.json 15:48:41 INFO - Downloading https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:48:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:48:41 INFO - Downloaded 989 bytes. 15:48:41 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:48:41 INFO - Using the following test package requirements: 15:48:41 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:48:41 INFO - u'common': [u'target.common.tests.zip'], 15:48:41 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:48:41 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:48:41 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:48:41 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:48:41 INFO - u'mozbase': [u'target.common.tests.zip'], 15:48:41 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:48:41 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:48:41 INFO - u'web-platform': [u'target.common.tests.zip', 15:48:41 INFO - u'target.web-platform.tests.tar.gz'], 15:48:41 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:48:41 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:48:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.common.tests.zip 15:48:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:48:41 INFO - Fetch https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.common.tests.zip into memory 15:48:43 INFO - Content-Length response header: 55023786 15:48:43 INFO - Bytes received: 55023786 15:48:53 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:48:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.common.tests.zip 15:48:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:48:53 INFO - Fetch https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.common.tests.zip into memory 15:48:54 INFO - Content-Length response header: 55023786 15:48:54 INFO - Bytes received: 55023786 15:49:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.talos.tests.zip 15:49:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:49:08 INFO - Fetch https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.talos.tests.zip into memory 15:49:08 INFO - Content-Length response header: 14496665 15:49:08 INFO - Bytes received: 14496665 15:49:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:09 INFO - trying https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.tar.bz2 15:49:09 INFO - Downloading https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:49:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:49:10 INFO - Downloaded 62837949 bytes. 15:49:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.tar.bz2 15:49:10 INFO - mkdir: /builds/slave/test/properties 15:49:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:49:10 INFO - Writing to file /builds/slave/test/properties/build_url 15:49:10 INFO - Contents: 15:49:10 INFO - build_url:https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.tar.bz2 15:49:10 INFO - Running post-action listener: _resource_record_post_action 15:49:10 INFO - Running post-action listener: find_tests_for_verification 15:49:10 INFO - Running post-action listener: set_extra_try_arguments 15:49:10 INFO - [mozharness: 2017-09-07 22:49:10.174008Z] Finished download-and-extract step (success) 15:49:10 INFO - [mozharness: 2017-09-07 22:49:10.174104Z] Running populate-webroot step. 15:49:10 INFO - Running pre-action listener: _resource_record_pre_action 15:49:10 INFO - Running main action method: populate_webroot 15:49:10 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:49:10 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:49:10 INFO - 'tests': ['tresize', 15:49:10 INFO - 'tcanvasmark']}, 15:49:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:49:10 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:49:10 INFO - 'tests': ['dromaeo_css', 15:49:10 INFO - 'kraken']}, 15:49:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:49:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:49:10 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:49:10 INFO - 'talos_options': ['--disable-stylo'], 15:49:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:49:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:49:10 INFO - 'tests': ['damp', 'tps']}, 15:49:10 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:49:10 INFO - 'talos_options': ['--disable-stylo'], 15:49:10 INFO - 'tests': ['damp', 'tps']}, 15:49:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:49:10 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:49:10 INFO - 'tests': ['dromaeo_dom']}, 15:49:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:49:10 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:49:10 INFO - 'tests': ['basic_compositor_video', 15:49:10 INFO - 'glvideo']}, 15:49:10 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:49:10 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:49:10 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:49:10 INFO - 'talos_options': ['--disable-stylo'], 15:49:10 INFO - 'tests': ['ts_paint_webext', 15:49:10 INFO - 'tp5o_webext']}, 15:49:10 INFO - 'other-e10s': {'tests': ['a11yr', 15:49:10 INFO - 'ts_paint', 15:49:10 INFO - 'tpaint', 15:49:10 INFO - 'sessionrestore', 15:49:10 INFO - 'sessionrestore_many_windows', 15:49:10 INFO - 'sessionrestore_no_auto_restore', 15:49:10 INFO - 'tabpaint']}, 15:49:10 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:49:10 INFO - 'tests': ['a11yr', 15:49:10 INFO - 'ts_paint', 15:49:10 INFO - 'tpaint', 15:49:10 INFO - 'sessionrestore', 15:49:10 INFO - 'sessionrestore_many_windows', 15:49:10 INFO - 'sessionrestore_no_auto_restore', 15:49:10 INFO - 'tabpaint']}, 15:49:10 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:49:10 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:49:10 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:49:10 INFO - 'tests': ['bloom_basic_singleton']}, 15:49:10 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:49:10 INFO - 'tests': ['bloom_basic']}, 15:49:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:49:10 INFO - 'tsvgr_opacity', 15:49:10 INFO - 'tart', 15:49:10 INFO - 'tscrollx', 15:49:10 INFO - 'tsvg_static']}, 15:49:10 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:49:10 INFO - 'tests': ['tsvgx', 15:49:10 INFO - 'tsvgr_opacity', 15:49:10 INFO - 'tart', 15:49:10 INFO - 'tscrollx', 15:49:10 INFO - 'tsvg_static']}, 15:49:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:49:10 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:49:10 INFO - 'talos_options': ['--disable-stylo'], 15:49:10 INFO - 'tests': ['tp5o']}, 15:49:10 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:49:10 INFO - 'talos_options': ['--mitmproxy', 15:49:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:49:10 INFO - '--firstNonBlankPaint'], 15:49:10 INFO - 'tests': ['tp6_google', 15:49:10 INFO - 'tp6_youtube', 15:49:10 INFO - 'tp6_amazon', 15:49:10 INFO - 'tp6_facebook']}, 15:49:10 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:49:10 INFO - 'talos_options': ['--disable-stylo', 15:49:10 INFO - '--mitmproxy', 15:49:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:49:10 INFO - '--firstNonBlankPaint'], 15:49:10 INFO - 'tests': ['tp6_google', 15:49:10 INFO - 'tp6_youtube', 15:49:10 INFO - 'tp6_amazon', 15:49:10 INFO - 'tp6_facebook']}, 15:49:10 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:49:10 INFO - 'talos_options': ['--stylo-threads=1', 15:49:10 INFO - '--mitmproxy', 15:49:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:49:10 INFO - '--firstNonBlankPaint'], 15:49:10 INFO - 'tests': ['tp6_google', 15:49:10 INFO - 'tp6_youtube', 15:49:10 INFO - 'tp6_amazon', 15:49:10 INFO - 'tp6_facebook']}, 15:49:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:49:10 INFO - 'talos_options': ['--xperf_path', 15:49:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:49:10 INFO - 'tests': ['tp5n']}, 15:49:10 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:49:10 INFO - 'talos_options': ['--disable-stylo', 15:49:10 INFO - '--xperf_path', 15:49:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:49:10 INFO - 'tests': ['tp5n']}}} 15:49:10 INFO - Running post-action listener: _resource_record_post_action 15:49:10 INFO - [mozharness: 2017-09-07 22:49:10.188924Z] Finished populate-webroot step (success) 15:49:10 INFO - [mozharness: 2017-09-07 22:49:10.189013Z] Running create-virtualenv step. 15:49:10 INFO - Running pre-action listener: _resource_record_pre_action 15:49:10 INFO - Running main action method: create_virtualenv 15:49:10 INFO - Creating virtualenv /builds/slave/test/build/venv 15:49:10 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:49:10 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:49:10 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:49:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:49:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:49:10 INFO - Installing distribute.............................................................................................................................................................................................done. 15:49:11 INFO - Installing pip...............done. 15:49:11 INFO - Return code: 0 15:49:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:49:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:49:11 INFO - Reading from file tmpfile_stdout 15:49:11 INFO - Output received: 15:49:11 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:49:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2480a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2484090>, '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 0x2484d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x247ef90>, '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': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', '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 15:49:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:49:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:49:11 INFO - Using env: {'DISPLAY': ':0', 15:49:11 INFO - 'HOME': '/home/cltbld', 15:49:11 INFO - 'LANG': 'en_US.UTF-8', 15:49:11 INFO - 'LANGUAGE': 'en_US:en', 15:49:11 INFO - 'LOGNAME': 'cltbld', 15:49:11 INFO - 'MAIL': '/var/mail/cltbld', 15:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:11 INFO - 'MOZ_NO_REMOTE': '1', 15:49:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:11 INFO - 'NO_EM_RESTART': '1', 15:49:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:11 INFO - 'PWD': '/builds/slave/test', 15:49:11 INFO - 'SHELL': '/bin/bash', 15:49:11 INFO - 'SHLVL': '1', 15:49:11 INFO - 'TERM': 'linux', 15:49:11 INFO - 'TMOUT': '86400', 15:49:11 INFO - 'USER': 'cltbld', 15:49:11 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', 15:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:11 INFO - '_': '/tools/buildbot/bin/python'} 15:49:12 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:49:12 INFO - Downloading/unpacking pip>=1.5 15:49:12 INFO - Running setup.py egg_info for package pip 15:49:12 INFO - warning: no files found matching 'pip/cacert.pem' 15:49:12 INFO - warning: no files found matching '*.html' under directory 'docs' 15:49:12 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:49:12 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:49:12 INFO - Installing collected packages: pip 15:49:12 INFO - Found existing installation: pip 1.1 15:49:12 INFO - Uninstalling pip: 15:49:12 INFO - Successfully uninstalled pip 15:49:12 INFO - Running setup.py install for pip 15:49:12 INFO - warning: no files found matching 'pip/cacert.pem' 15:49:12 INFO - warning: no files found matching '*.html' under directory 'docs' 15:49:12 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:49:12 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:49:12 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:49:12 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:49:12 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:49:12 INFO - Successfully installed pip 15:49:12 INFO - Cleaning up... 15:49:12 INFO - Return code: 0 15:49:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:49:12 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')]} 15:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:12 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')]} 15:49:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:12 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 0x21141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2480a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2484090>, '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 0x2484d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x247ef90>, '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': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', '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 15:49:12 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 15:49:12 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 15:49:12 INFO - Using env: {'DISPLAY': ':0', 15:49:12 INFO - 'HOME': '/home/cltbld', 15:49:12 INFO - 'LANG': 'en_US.UTF-8', 15:49:12 INFO - 'LANGUAGE': 'en_US:en', 15:49:12 INFO - 'LOGNAME': 'cltbld', 15:49:12 INFO - 'MAIL': '/var/mail/cltbld', 15:49:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:12 INFO - 'MOZ_NO_REMOTE': '1', 15:49:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:12 INFO - 'NO_EM_RESTART': '1', 15:49:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:12 INFO - 'PWD': '/builds/slave/test', 15:49:12 INFO - 'SHELL': '/bin/bash', 15:49:12 INFO - 'SHLVL': '1', 15:49:12 INFO - 'TERM': 'linux', 15:49:12 INFO - 'TMOUT': '86400', 15:49:12 INFO - 'USER': 'cltbld', 15:49:12 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', 15:49:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:12 INFO - '_': '/tools/buildbot/bin/python'} 15:49:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:12 INFO - Downloading/unpacking psutil>=3.1.1 15:49:12 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 15:49:12 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 15:49:12 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 15:49:12 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 15:49:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:49:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:49:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:49:13 INFO - Installing collected packages: psutil 15:49:13 INFO - Running setup.py install for psutil 15:49:13 INFO - building 'psutil._psutil_linux' extension 15:49:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:49:14 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 15:49:14 INFO - building 'psutil._psutil_posix' extension 15:49:14 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 15:49:14 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 15:49:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:49:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:49:14 INFO - Successfully installed psutil 15:49:14 INFO - Cleaning up... 15:49:14 INFO - Return code: 0 15:49:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:49:14 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')]} 15:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:14 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')]} 15:49:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:14 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 0x21141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2480a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2484090>, '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 0x2484d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x247ef90>, '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': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', '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 15:49:14 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 15:49:14 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 15:49:14 INFO - Using env: {'DISPLAY': ':0', 15:49:14 INFO - 'HOME': '/home/cltbld', 15:49:14 INFO - 'LANG': 'en_US.UTF-8', 15:49:14 INFO - 'LANGUAGE': 'en_US:en', 15:49:14 INFO - 'LOGNAME': 'cltbld', 15:49:14 INFO - 'MAIL': '/var/mail/cltbld', 15:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:14 INFO - 'MOZ_NO_REMOTE': '1', 15:49:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:14 INFO - 'NO_EM_RESTART': '1', 15:49:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:14 INFO - 'PWD': '/builds/slave/test', 15:49:14 INFO - 'SHELL': '/bin/bash', 15:49:14 INFO - 'SHLVL': '1', 15:49:14 INFO - 'TERM': 'linux', 15:49:14 INFO - 'TMOUT': '86400', 15:49:14 INFO - 'USER': 'cltbld', 15:49:14 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', 15:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:14 INFO - '_': '/tools/buildbot/bin/python'} 15:49:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:49:14 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 15:49:14 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 15:49:14 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 15:49:14 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 15:49:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:49:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:49:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:49:15 INFO - Installing collected packages: mozsystemmonitor 15:49:15 INFO - Running setup.py install for mozsystemmonitor 15:49:15 INFO - Successfully installed mozsystemmonitor 15:49:15 INFO - Cleaning up... 15:49:15 INFO - Return code: 0 15:49:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:49:15 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')]} 15:49:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:15 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')]} 15:49:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:15 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 0x21141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2480a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2484090>, '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 0x2484d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x247ef90>, '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': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', '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 15:49:15 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 15:49:15 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 15:49:15 INFO - Using env: {'DISPLAY': ':0', 15:49:15 INFO - 'HOME': '/home/cltbld', 15:49:15 INFO - 'LANG': 'en_US.UTF-8', 15:49:15 INFO - 'LANGUAGE': 'en_US:en', 15:49:15 INFO - 'LOGNAME': 'cltbld', 15:49:15 INFO - 'MAIL': '/var/mail/cltbld', 15:49:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:15 INFO - 'MOZ_NO_REMOTE': '1', 15:49:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:15 INFO - 'NO_EM_RESTART': '1', 15:49:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:15 INFO - 'PWD': '/builds/slave/test', 15:49:15 INFO - 'SHELL': '/bin/bash', 15:49:15 INFO - 'SHLVL': '1', 15:49:15 INFO - 'TERM': 'linux', 15:49:15 INFO - 'TMOUT': '86400', 15:49:15 INFO - 'USER': 'cltbld', 15:49:15 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', 15:49:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:15 INFO - '_': '/tools/buildbot/bin/python'} 15:49:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:16 INFO - Downloading/unpacking jsonschema==2.5.1 15:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:49:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:17 INFO - Downloading functools32-3.2.3-2.tar.gz 15:49:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:49:17 INFO - warning: no files found matching '*.txt' 15:49:17 INFO - no previously-included directories found matching 'build' 15:49:17 INFO - no previously-included directories found matching 'dist' 15:49:17 INFO - no previously-included directories found matching '.git*' 15:49:17 INFO - Installing collected packages: jsonschema, functools32 15:49:17 INFO - Running setup.py install for functools32 15:49:17 INFO - warning: no files found matching '*.txt' 15:49:17 INFO - no previously-included directories found matching 'build' 15:49:17 INFO - no previously-included directories found matching 'dist' 15:49:17 INFO - no previously-included directories found matching '.git*' 15:49:17 INFO - Successfully installed jsonschema functools32 15:49:17 INFO - Cleaning up... 15:49:17 INFO - Return code: 0 15:49:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:49:17 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')]} 15:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:17 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')]} 15:49:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:17 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 0x21141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2480a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2484090>, '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 0x2484d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x247ef90>, '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': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', '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 15:49:17 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 15:49:17 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 15:49:17 INFO - Using env: {'DISPLAY': ':0', 15:49:17 INFO - 'HOME': '/home/cltbld', 15:49:17 INFO - 'LANG': 'en_US.UTF-8', 15:49:17 INFO - 'LANGUAGE': 'en_US:en', 15:49:17 INFO - 'LOGNAME': 'cltbld', 15:49:17 INFO - 'MAIL': '/var/mail/cltbld', 15:49:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:17 INFO - 'MOZ_NO_REMOTE': '1', 15:49:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:17 INFO - 'NO_EM_RESTART': '1', 15:49:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:17 INFO - 'PWD': '/builds/slave/test', 15:49:17 INFO - 'SHELL': '/bin/bash', 15:49:17 INFO - 'SHLVL': '1', 15:49:17 INFO - 'TERM': 'linux', 15:49:17 INFO - 'TMOUT': '86400', 15:49:17 INFO - 'USER': 'cltbld', 15:49:17 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', 15:49:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:17 INFO - '_': '/tools/buildbot/bin/python'} 15:49:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:49:17 INFO - Cleaning up... 15:49:17 INFO - Return code: 0 15:49:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:49:17 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')]} 15:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:17 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')]} 15:49:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:17 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 0x21141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2480a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2484090>, '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 0x2484d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x247ef90>, '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': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', '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 15:49:17 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 15:49:17 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 15:49:17 INFO - Using env: {'DISPLAY': ':0', 15:49:17 INFO - 'HOME': '/home/cltbld', 15:49:17 INFO - 'LANG': 'en_US.UTF-8', 15:49:17 INFO - 'LANGUAGE': 'en_US:en', 15:49:17 INFO - 'LOGNAME': 'cltbld', 15:49:17 INFO - 'MAIL': '/var/mail/cltbld', 15:49:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:17 INFO - 'MOZ_NO_REMOTE': '1', 15:49:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:17 INFO - 'NO_EM_RESTART': '1', 15:49:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:17 INFO - 'PWD': '/builds/slave/test', 15:49:17 INFO - 'SHELL': '/bin/bash', 15:49:17 INFO - 'SHLVL': '1', 15:49:17 INFO - 'TERM': 'linux', 15:49:17 INFO - 'TMOUT': '86400', 15:49:17 INFO - 'USER': 'cltbld', 15:49:17 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', 15:49:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:17 INFO - '_': '/tools/buildbot/bin/python'} 15:49:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:18 INFO - Downloading/unpacking blobuploader==1.2.4 15:49:18 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 15:49:18 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 15:49:18 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 15:49:18 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 15:49:19 INFO - Downloading blobuploader-1.2.4.tar.gz 15:49:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:49:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:49:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:19 INFO - Downloading docopt-0.6.1.tar.gz 15:49:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:49:19 INFO - Installing collected packages: blobuploader, requests, docopt 15:49:19 INFO - Running setup.py install for blobuploader 15:49:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:49:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:49:19 INFO - Running setup.py install for requests 15:49:20 INFO - Running setup.py install for docopt 15:49:20 INFO - Successfully installed blobuploader requests docopt 15:49:20 INFO - Cleaning up... 15:49:20 INFO - Return code: 0 15:49:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:49:20 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')]} 15:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:20 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')]} 15:49:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:20 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 0x21141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2480a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2484090>, '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 0x2484d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x247ef90>, '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': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', '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 15:49:20 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 15:49:20 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 15:49:20 INFO - Using env: {'DISPLAY': ':0', 15:49:20 INFO - 'HOME': '/home/cltbld', 15:49:20 INFO - 'LANG': 'en_US.UTF-8', 15:49:20 INFO - 'LANGUAGE': 'en_US:en', 15:49:20 INFO - 'LOGNAME': 'cltbld', 15:49:20 INFO - 'MAIL': '/var/mail/cltbld', 15:49:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:20 INFO - 'MOZ_NO_REMOTE': '1', 15:49:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:20 INFO - 'NO_EM_RESTART': '1', 15:49:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:20 INFO - 'PWD': '/builds/slave/test', 15:49:20 INFO - 'SHELL': '/bin/bash', 15:49:20 INFO - 'SHLVL': '1', 15:49:20 INFO - 'TERM': 'linux', 15:49:20 INFO - 'TMOUT': '86400', 15:49:20 INFO - 'USER': 'cltbld', 15:49:20 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', 15:49:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:20 INFO - '_': '/tools/buildbot/bin/python'} 15:49:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:49:20 INFO - Running setup.py (path:/tmp/pip-oO9drH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:49:20 INFO - Running setup.py (path:/tmp/pip-KoAehc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:49:20 INFO - Running setup.py (path:/tmp/pip-psmWXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:49:20 INFO - Running setup.py (path:/tmp/pip-J5IES3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:49:20 INFO - Running setup.py (path:/tmp/pip-X_b8y1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:49:20 INFO - Running setup.py (path:/tmp/pip-T5WtUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:49:20 INFO - Running setup.py (path:/tmp/pip-ys5uId-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:49:21 INFO - Running setup.py (path:/tmp/pip-4DNQIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:49:21 INFO - Running setup.py (path:/tmp/pip-otY7mx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:49:21 INFO - Running setup.py (path:/tmp/pip-Uq1xuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:49:21 INFO - Running setup.py (path:/tmp/pip-qp6MV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:49:21 INFO - Running setup.py (path:/tmp/pip-K1pXPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:49:21 INFO - Running setup.py (path:/tmp/pip-FSyy1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:49:21 INFO - Running setup.py (path:/tmp/pip-cRyjoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:21 INFO - Running setup.py (path:/tmp/pip-LOgMNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:49:21 INFO - Running setup.py (path:/tmp/pip-26tTDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:49:21 INFO - Running setup.py (path:/tmp/pip-JyqPgQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:49:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:49:21 INFO - Running setup.py install for manifestparser 15:49:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:49:21 INFO - Running setup.py install for mozcrash 15:49:21 INFO - Running setup.py install for mozdebug 15:49:22 INFO - Running setup.py install for mozdevice 15:49:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:49:22 INFO - Running setup.py install for mozfile 15:49:22 INFO - Running setup.py install for mozhttpd 15:49:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:49:22 INFO - Running setup.py install for mozinfo 15:49:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:49:22 INFO - Running setup.py install for mozInstall 15:49:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:49:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:49:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:49:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:49:22 INFO - Running setup.py install for mozleak 15:49:22 INFO - Running setup.py install for mozlog 15:49:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:49:22 INFO - Running setup.py install for moznetwork 15:49:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:49:22 INFO - Running setup.py install for mozprocess 15:49:22 INFO - Running setup.py install for mozprofile 15:49:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:49:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:49:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:49:23 INFO - Running setup.py install for mozrunner 15:49:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:49:23 INFO - Running setup.py install for mozscreenshot 15:49:23 INFO - Running setup.py install for moztest 15:49:23 INFO - Running setup.py install for mozversion 15:49:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:49:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:49:23 INFO - Cleaning up... 15:49:23 INFO - Return code: 0 15:49:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:23 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 0x21141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2480a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2484090>, '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 0x2484d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x247ef90>, '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': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', '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 15:49:23 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 15:49:23 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 15:49:23 INFO - Using env: {'DISPLAY': ':0', 15:49:23 INFO - 'HOME': '/home/cltbld', 15:49:23 INFO - 'LANG': 'en_US.UTF-8', 15:49:23 INFO - 'LANGUAGE': 'en_US:en', 15:49:23 INFO - 'LOGNAME': 'cltbld', 15:49:23 INFO - 'MAIL': '/var/mail/cltbld', 15:49:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:23 INFO - 'MOZ_NO_REMOTE': '1', 15:49:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:23 INFO - 'NO_EM_RESTART': '1', 15:49:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:23 INFO - 'PWD': '/builds/slave/test', 15:49:23 INFO - 'SHELL': '/bin/bash', 15:49:23 INFO - 'SHLVL': '1', 15:49:23 INFO - 'TERM': 'linux', 15:49:23 INFO - 'TMOUT': '86400', 15:49:23 INFO - 'USER': 'cltbld', 15:49:23 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', 15:49:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:23 INFO - '_': '/tools/buildbot/bin/python'} 15:49:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:49:23 INFO - Running setup.py (path:/tmp/pip-qOakA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:49:23 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)) 15:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:49:23 INFO - Running setup.py (path:/tmp/pip-hhyKtz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:49:23 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)) 15:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:49:23 INFO - Running setup.py (path:/tmp/pip-3CrhMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:49:23 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)) 15:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:49:23 INFO - Running setup.py (path:/tmp/pip-S_KIIW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:49:23 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)) 15:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:49:23 INFO - Running setup.py (path:/tmp/pip-dGDFXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:49:24 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)) 15:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:49:24 INFO - Running setup.py (path:/tmp/pip-Nr2tcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:49:24 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)) 15:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:49:24 INFO - Running setup.py (path:/tmp/pip-uosL2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:49:24 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)) 15:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:49:24 INFO - Running setup.py (path:/tmp/pip-M3aS47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:49:24 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)) 15:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:49:24 INFO - Running setup.py (path:/tmp/pip-_AfpiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:49:24 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)) 15:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:49:24 INFO - Running setup.py (path:/tmp/pip-HimaQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:49:24 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)) 15:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:49:24 INFO - Running setup.py (path:/tmp/pip-zup8Vs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:49:24 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)) 15:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:49:24 INFO - Running setup.py (path:/tmp/pip-bw68Ld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:49:24 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)) 15:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:49:24 INFO - Running setup.py (path:/tmp/pip-HJ3EwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:49:24 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)) 15:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:49:24 INFO - Running setup.py (path:/tmp/pip-kVvhog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:49:24 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)) 15:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:24 INFO - Running setup.py (path:/tmp/pip-Lp7Eth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:24 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)) 15:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:49:24 INFO - Running setup.py (path:/tmp/pip-IM2jhM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:49:24 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)) 15:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:49:24 INFO - Running setup.py (path:/tmp/pip-qPXehU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:49:24 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)) 15:49:24 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)) 15:49:24 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)) 15:49:24 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)) 15:49:24 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)) 15:49:24 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)) 15:49:24 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)) 15:49:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:49:24 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 15:49:24 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 15:49:24 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 15:49:24 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 15:49:26 INFO - Downloading blessings-1.6.tar.gz 15:49:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:49:26 INFO - Installing collected packages: blessings 15:49:26 INFO - Running setup.py install for blessings 15:49:26 INFO - Successfully installed blessings 15:49:26 INFO - Cleaning up... 15:49:26 INFO - Return code: 0 15:49:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:49:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:49:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:49:26 INFO - Reading from file tmpfile_stdout 15:49:26 INFO - Current package versions: 15:49:26 INFO - argparse == 1.2.1 15:49:26 INFO - blessings == 1.6 15:49:26 INFO - blobuploader == 1.2.4 15:49:26 INFO - docopt == 0.6.1 15:49:26 INFO - functools32 == 3.2.3-2 15:49:26 INFO - jsonschema == 2.5.1 15:49:26 INFO - manifestparser == 1.1 15:49:26 INFO - mozInstall == 1.13 15:49:26 INFO - mozcrash == 1.0 15:49:26 INFO - mozdebug == 0.1 15:49:26 INFO - mozdevice == 0.51 15:49:26 INFO - mozfile == 1.2 15:49:26 INFO - mozhttpd == 0.7 15:49:26 INFO - mozinfo == 0.10 15:49:26 INFO - mozleak == 0.1 15:49:26 INFO - mozlog == 3.5 15:49:26 INFO - moznetwork == 0.27 15:49:26 INFO - mozprocess == 0.25 15:49:26 INFO - mozprofile == 0.28 15:49:26 INFO - mozrunner == 6.13 15:49:26 INFO - mozscreenshot == 0.1 15:49:26 INFO - mozsystemmonitor == 0.3 15:49:26 INFO - moztest == 0.8 15:49:26 INFO - mozversion == 1.4 15:49:26 INFO - psutil == 3.1.1 15:49:26 INFO - requests == 1.2.3 15:49:26 INFO - wsgiref == 0.1.2 15:49:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:49:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:26 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 0x21141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2480a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2484090>, '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 0x2484d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x247ef90>, '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': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', '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 15:49:26 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 15:49:26 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 15:49:26 INFO - Using env: {'DISPLAY': ':0', 15:49:26 INFO - 'HOME': '/home/cltbld', 15:49:26 INFO - 'LANG': 'en_US.UTF-8', 15:49:26 INFO - 'LANGUAGE': 'en_US:en', 15:49:26 INFO - 'LOGNAME': 'cltbld', 15:49:26 INFO - 'MAIL': '/var/mail/cltbld', 15:49:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:26 INFO - 'MOZ_NO_REMOTE': '1', 15:49:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:26 INFO - 'NO_EM_RESTART': '1', 15:49:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:26 INFO - 'PWD': '/builds/slave/test', 15:49:26 INFO - 'SHELL': '/bin/bash', 15:49:26 INFO - 'SHLVL': '1', 15:49:26 INFO - 'TERM': 'linux', 15:49:26 INFO - 'TMOUT': '86400', 15:49:26 INFO - 'USER': 'cltbld', 15:49:26 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', 15:49:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:26 INFO - '_': '/tools/buildbot/bin/python'} 15:49:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:26 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)) 15:49:26 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)) 15:49:26 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)) 15:49:26 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)) 15:49:26 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)) 15:49:26 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)) 15:49:26 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)) 15:49:26 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)) 15:49:26 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)) 15:49:26 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:49:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:49:27 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)) 15:49:27 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)) 15:49:27 INFO - Installing collected packages: simplejson 15:49:27 INFO - Running setup.py install for simplejson 15:49:27 INFO - building 'simplejson._speedups' extension 15:49:27 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 15:49:28 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 15:49:28 INFO - Successfully installed simplejson 15:49:28 INFO - Cleaning up... 15:49:28 INFO - Return code: 0 15:49:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:49: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')]} 15:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49: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')]} 15:49:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2480a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2484090>, '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 0x2484d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x247ef90>, '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': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', '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 15:49: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', 'jsonschema'] in /builds/slave/test/build 15:49: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 jsonschema 15:49:28 INFO - Using env: {'DISPLAY': ':0', 15:49:28 INFO - 'HOME': '/home/cltbld', 15:49:28 INFO - 'LANG': 'en_US.UTF-8', 15:49:28 INFO - 'LANGUAGE': 'en_US:en', 15:49:28 INFO - 'LOGNAME': 'cltbld', 15:49:28 INFO - 'MAIL': '/var/mail/cltbld', 15:49:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:28 INFO - 'MOZ_NO_REMOTE': '1', 15:49:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:28 INFO - 'NO_EM_RESTART': '1', 15:49:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:28 INFO - 'PWD': '/builds/slave/test', 15:49:28 INFO - 'SHELL': '/bin/bash', 15:49:28 INFO - 'SHLVL': '1', 15:49:28 INFO - 'TERM': 'linux', 15:49:28 INFO - 'TMOUT': '86400', 15:49:28 INFO - 'USER': 'cltbld', 15:49:28 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', 15:49:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:28 INFO - '_': '/tools/buildbot/bin/python'} 15:49:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:49:29 INFO - Cleaning up... 15:49:29 INFO - Return code: 0 15:49:29 INFO - Running post-action listener: _resource_record_post_action 15:49:29 INFO - Running post-action listener: _start_resource_monitoring 15:49:29 INFO - Starting resource monitoring. 15:49:29 INFO - [mozharness: 2017-09-07 22:49:29.166657Z] Finished create-virtualenv step (success) 15:49:29 INFO - [mozharness: 2017-09-07 22:49:29.167171Z] Running install step. 15:49:29 INFO - Running pre-action listener: _resource_record_pre_action 15:49:29 INFO - Running main action method: install 15:49:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:49:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:49:29 INFO - Reading from file tmpfile_stdout 15:49:29 INFO - Detecting whether we're running mozinstall >=1.0... 15:49:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:49:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:49:29 INFO - Reading from file tmpfile_stdout 15:49:29 INFO - Output received: 15:49:29 INFO - Usage: mozinstall [options] installer 15:49:29 INFO - Options: 15:49:29 INFO - -h, --help show this help message and exit 15:49:29 INFO - -d DEST, --destination=DEST 15:49:29 INFO - Directory to install application into. [default: 15:49:29 INFO - "/builds/slave/test"] 15:49:29 INFO - --app=APP Application being installed. [default: firefox] 15:49:29 INFO - mkdir: /builds/slave/test/build/application 15:49:29 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'] 15:49:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:49:46 INFO - Reading from file tmpfile_stdout 15:49:46 INFO - Output received: 15:49:46 INFO - /builds/slave/test/build/application/firefox/firefox 15:49:46 INFO - Running post-action listener: _resource_record_post_action 15:49:46 INFO - [mozharness: 2017-09-07 22:49:46.103459Z] Finished install step (success) 15:49:46 INFO - [mozharness: 2017-09-07 22:49:46.103710Z] Running setup-mitmproxy step. 15:49:46 INFO - Running pre-action listener: _resource_record_pre_action 15:49:46 INFO - Running main action method: setup_mitmproxy 15:49:46 INFO - Skipping: mitmproxy is not required 15:49:46 INFO - Running post-action listener: _resource_record_post_action 15:49:46 INFO - [mozharness: 2017-09-07 22:49:46.104849Z] Finished setup-mitmproxy step (success) 15:49:46 INFO - [mozharness: 2017-09-07 22:49:46.105067Z] Running run-tests step. 15:49:46 INFO - Running pre-action listener: _resource_record_pre_action 15:49:46 INFO - Running pre-action listener: _set_gcov_prefix 15:49:46 INFO - Running main action method: run_tests 15:49:46 WARNING - Try message not found. 15:49:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:49:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:49:46 INFO - Python 2.7.3 15:49:46 INFO - Return code: 0 15:49:46 INFO - grabbing minidump binary from tooltool 15:49:46 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')]} 15:49:46 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 0x2484090>, '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 0x2484d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x247ef90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:49:46 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 15:49:46 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 15:49:46 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 15:49:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:49:46 INFO - Return code: 0 15:49:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:49:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:49:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:49:46 INFO - ENV: RUST_BACKTRACE is now full 15:49:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:49:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:49:46 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/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--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 15:49:46 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/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-049 --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 15:49:46 INFO - Using env: {'DISPLAY': ':0', 15:49:46 INFO - 'HOME': '/home/cltbld', 15:49:46 INFO - 'LANG': 'en_US.UTF-8', 15:49:46 INFO - 'LANGUAGE': 'en_US:en', 15:49:46 INFO - 'LOGNAME': 'cltbld', 15:49:46 INFO - 'MAIL': '/var/mail/cltbld', 15:49:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:49:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:49:46 INFO - 'MOZ_ACCELERATED': '1', 15:49:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:46 INFO - 'MOZ_NO_REMOTE': '1', 15:49:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:49:46 INFO - 'MOZ_WEBRENDER': '1', 15:49:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:46 INFO - 'NO_EM_RESTART': '1', 15:49:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:46 INFO - 'PWD': '/builds/slave/test', 15:49:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:49:46 INFO - 'RUST_BACKTRACE': 'full', 15:49:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:49:46 INFO - 'SHELL': '/bin/bash', 15:49:46 INFO - 'SHLVL': '1', 15:49:46 INFO - 'TERM': 'linux', 15:49:46 INFO - 'TMOUT': '86400', 15:49:46 INFO - 'USER': 'cltbld', 15:49:46 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', 15:49:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:46 INFO - '_': '/tools/buildbot/bin/python'} 15:49:46 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/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--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 15:49:46 INFO - mozversion application_buildid: 20170907220040 15:49:46 INFO - mozversion application_changeset: b4c1ad9565ee9d00d96501c4a83083daf25c1413 15:49:46 INFO - mozversion application_display_name: Nightly 15:49:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:49:46 INFO - mozversion application_name: Firefox 15:49:46 INFO - mozversion application_remotingname: firefox 15:49:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:49:46 INFO - mozversion application_vendor: Mozilla 15:49:46 INFO - mozversion application_version: 57.0a1 15:49:46 INFO - mozversion platform_buildid: 20170907220040 15:49:46 INFO - mozversion platform_changeset: b4c1ad9565ee9d00d96501c4a83083daf25c1413 15:49:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:49:46 INFO - mozversion platform_version: 57.0a1 15:49:46 INFO - using testdate: 1504824586 15:49:46 INFO - actual date: 1504824586 15:49:46 INFO - starting webserver on 'localhost:55349' 15:49:46 INFO - SUITE-START | Running 2 tests 15:49:46 INFO - TEST-START | dromaeo_css 15:49:46 INFO - Initialising browser for dromaeo_css test... 15:49:46 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55349/getInfo.html -profile /tmp/tmpOE0U8L/profile 15:49:46 INFO - TEST-INFO | started process 9150 (/builds/slave/test/build/application/firefox/firefox http://localhost:55349/getInfo.html) 15:49:52 INFO - TEST-INFO | 9150: exit 0 15:49:52 INFO - Browser initialized. 15:49:52 INFO - Running cycle 1/1 for dromaeo_css test... 15:49:52 INFO - Using env: {'DISPLAY': ':0', 15:49:52 INFO - 'HOME': '/home/cltbld', 15:49:52 INFO - 'JSGC_DISABLE_POISONING': '1', 15:49:52 INFO - 'LANG': 'en_US.UTF-8', 15:49:52 INFO - 'LANGUAGE': 'en_US:en', 15:49:52 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:49:52 INFO - 'LOGNAME': 'cltbld', 15:49:52 INFO - 'MAIL': '/var/mail/cltbld', 15:49:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:49:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:49:52 INFO - 'MOZ_ACCELERATED': '1', 15:49:52 INFO - 'MOZ_CRASHREPORTER': '1', 15:49:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:52 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:49:52 INFO - 'MOZ_NO_REMOTE': '1', 15:49:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:49:52 INFO - 'MOZ_WEBRENDER': '1', 15:49:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:52 INFO - 'NO_EM_RESTART': '1', 15:49:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:52 INFO - 'PWD': '/builds/slave/test', 15:49:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:49:52 INFO - 'RUST_BACKTRACE': 'full', 15:49:52 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:49:52 INFO - 'SHELL': '/bin/bash', 15:49:52 INFO - 'SHLVL': '1', 15:49:52 INFO - 'TERM': 'linux', 15:49:52 INFO - 'TMOUT': '86400', 15:49:52 INFO - 'USER': 'cltbld', 15:49:52 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', 15:49:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:52 INFO - '_': '/tools/buildbot/bin/python'} 15:49:52 INFO - TEST-INFO | started process 9356 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOE0U8L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:49:53 INFO - PID 9356 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:49:53 INFO - PID 9356 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:49:53 INFO - PID 9356 | 15:49:53 INFO - PID 9356 | (/builds/slave/test/build/application/firefox/firefox:9417): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:49:53 INFO - PID 9356 | 15:50:04 INFO - PID 9356 | RSS: Main: 212992000 15:50:04 INFO - PID 9356 | 15:53:01 INFO - PID 9356 | Cycle 1(1): loaded http://localhost:55349/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55349/tests/dromaeo/cssquery-ext.html) 15:53:03 INFO - PID 9356 | RSS: Main: 220725248 15:53:03 INFO - PID 9356 | 15:56:05 INFO - PID 9356 | Cycle 1(1): loaded http://localhost:55349/tests/dromaeo/cssquery-ext.html (next: http://localhost:55349/tests/dromaeo/cssquery-jquery.html) 15:56:06 INFO - PID 9356 | RSS: Main: 217763840 15:56:06 INFO - PID 9356 | 15:59:08 INFO - PID 9356 | Cycle 1(1): loaded http://localhost:55349/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55349/tests/dromaeo/cssquery-mootools.html) 15:59:09 INFO - PID 9356 | RSS: Main: 218214400 15:59:09 INFO - PID 9356 | 16:02:11 INFO - PID 9356 | Cycle 1(1): loaded http://localhost:55349/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55349/tests/dromaeo/cssquery-prototype.html) 16:02:11 INFO - PID 9356 | RSS: Main: 219328512 16:02:11 INFO - PID 9356 | 16:05:15 INFO - PID 9356 | Cycle 1(1): loaded http://localhost:55349/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55349/tests/dromaeo/cssquery-yui.html) 16:05:16 INFO - PID 9356 | RSS: Main: 222990336 16:05:16 INFO - PID 9356 | 16:08:18 INFO - PID 9356 | Cycle 1(1): loaded http://localhost:55349/tests/dromaeo/cssquery-yui.html (next: http://localhost:55349/tests/dromaeo/cssquery-dojo.html) 16:08:18 INFO - PID 9356 | RSS: Main: 222572544 16:08:18 INFO - PID 9356 | 16:08:18 INFO - PID 9356 | __start_tp_report 16:08:18 INFO - PID 9356 | _x_x_mozilla_page_load 16:08:18 INFO - PID 9356 | _x_x_mozilla_page_load_details 16:08:18 INFO - PID 9356 | |i|pagename|runs| 16:08:18 INFO - PID 9356 | |0;dojo.html;3092.381523695261;3121.5941927549416;3127.483965145751;3132.5301204819275;3136.7647426443014;6119.143319935201;6202.3487533808975;6203.069539569067;6233.127362169294;6267.811965641026;6139.754409823602;6140.754369825213;6141.539384546166;6143.10924915887;6168.136460895478;5685.346185188715;5703.57223208259;5712.600117991742;5719;5726.3987281335485;5742.224799652056;5752.108423194411;5762.798302059428;5802.941970580282;5816.854578635547;5677.432256774331;5718.085106382959;5734.856628584277;5740.7703691852275;5740.827775166752;5560.110382338807;5665.291838520185;5787.971060144714;5804.883902321951;5845.766169353221;5644.266245388073;5658.207850900836;5683.175939488751;5696.914546281809;5715.714214289269;6155.44600985909;6193.845153871144;6195.845103872394;6281.87436251277;6322.241331040259;6497.252815926182;6498.122753428274;6593.241777195636;6594.901076483857;6728.192616885956;6517.902231466532;6537.378948999837;6540.967295163541;6553.508486863504;6553.901691474632;6033.879322413527;6077;6079.6960151992225;6135.141080248769;6225.128482012522;6726.865462690719;6833.4191593715095;6856.41720453767;6910;6918.308169183142;6451.1936007999;6463.289038205888;6528.804135875931;6557.770478033246;6587.53887227899;6461.870762584722;6543.803685889431;6609.239937407164;6706.798796036127;6744.730210791611;6518.120053792677;6545.083688283644;6548.246951600531;6551;6562.179727534059;6547.181602299713;6555.770548030796;6578.177727784027;6594.505412094112;6611.041398997215;5247.081760691864;5255.710935898486;5295.152775555893;5311.628186027018;5329.8934021319355;4586.656000799953;4600.884977875513;4627.074585082997;4640.930386044212;4705.341252224691;5638.830835074954;5723.8569035774435;5724.599278050579;5725.8568535786935;5729.169270455761;6498.252700939358;6553.148090748172;6570.507211959122;6597.637129957802;6598.439132673777;5131.204663277198;5629.746661400247;5696.544276457874;5751.9424805751405;5752.568557358131;5707.258056452634;5719.227904232958;5772.595918285764;5800.9419905800405;5820.3888591698105;6499.317571655003;6517.771877984343;6594.340565943367;6601.141851559362;6606;6640.900386494303;6651.767188148391;6720.361565651256;6727.697253623696;6734.528582999241;6316.557840951089;6439.420452159328;6446.838829029124;6452.322506136788;6454.128692626436;6509.902351464637;6515.641639709862;6534.869302614016;6542.247641521096;6547.148870646785;6561.376669216417;6568.91612883888;6573.769918052845;6619.073329733745;6650.434713049445;6530.053142294436;6532.41208291256;6586.473082153512;6591;6599.868002640016;6477.643729594917;6487.772927947499;6494.935050649622;6579.144711187707;6582.111414958919;6120.632762034292;6271.62370257786;6409.487241020801;6472.967635161794;6485.383888530582;6363.5545511813725;6374.617522948638;6380;6399.200099987502;6432.903506447314;6049.909251361145;6145.477634401126;6153.138560601609;6210.844728881814;6219.720112595034 16:08:18 INFO - PID 9356 | |1;ext.html;734.8126227811907;747.2154238050048;747.9513831600949;749.123525475194;749.2806905370844;39749;40496.59503404965;40589.79705101474;43396.34905476413;43800;38529.61470385295;38538.4219236711;38573.228535429276;38591.61408385915;38866.22267554647;6873.381395674376;6993;7023.227444981048;7032.191298000745;7061.611611361373;7633.847323053536;7776.53340799551;7813.609319534029;7823.413244006694;7860.764177074669;7769.883451748228;7824.061112666488;7842.058952925657;7865.331446827026;7886;7720.498167619086;7817.843643127106;7841.059072911287;8018.799530011738;8018.919810801875;7805.687772489094;7864.056313242448;7873.133955264916;7874.05511338643;7889.289963903218;38570.80714596437;38854.22291554181;38879;38967.220655587014;39167;3536.2220311531423;3555.8933232002823;3572.499850020999;3580.8030558319283;3580.964190358089;32241.1939701507;32322.353552929046;32389.83805080983;32395.51406728901;32414;5223.399309079428;5223.738813059332;5224.4775522447835;5247.842564723064;5267.420583735786;189528;189787;190148;190154;190163;48988.510114898396;49020;49032.75483622559;49372;49689;46037.7698111514;53156.46843531515;53719.731401343524;53784;53968.460315397126;16165.515034548747;16177.352905883869;16276;16356.918215409085;16364.345426183057;13134.737305254033;13232.338383080807;13246.139000964906;13263.204207747565;13271.800922986164;14327.35526901292;14542;14613.780793287897;14736.189509576861;14818.184999824898;5728.971355143281;5827.125931110368;5954.225950626477;5997.430244126801;6012.428819262163;1020.2348238820884;1034.4724190662816;1038.2680210451613;1044.4255659387306;1049.2130901823632;23399.53200935972;23545.411364715677;23635.88182059079;23725.762742372357;23803.285901422638;1114.7101753544136;1122.315387613555;1135.9545618175346;1139.9544018239344;1142.7600203957263;12374.257544547356;12510.74978500425;12582.685432864251;12682.80975785364;12694.42875070624;228750;228815;228890;228996;229754;55506.44493555013;55528.44471555233;55580;55941.720291398284;56313.71843140758;1430.7996880436747;1462.9634259143393;1466.9119852809079;1475.1886462445611;1475.4614565683448;22543.66184507201;22990.770092298862;22996.42508937223;23179.188728394427;23446;38320.42519362156;38412.80793596014;38507.42238866363;38584.22831543297;38595.80702096471;191516;191817;192116;192119;194361;31431.371372571964;31570.684293156773;32235.677643224204;34964.65035349614;36154;43748;44198.337024944005;44336;44389.77805110954;44515.554844451144;31936.6806331943;32152.03543893687;32635.510467343483;32806.507902381;32926.50610240896;32213.35573288568;32817.179570510925;32819;32943.50584741279;33040.66959330376;36004.27991440209;36117.27765444729;36154.8192259037;36590.63409365872;36631.901042968755;31467.370652587277;31673.683263167073;32120.35759284848;32166.83916580402;32353.35293294168;23754.168604098773;23904.88047559751;24031.399215019763;24313.878430607732;24475 16:08:18 INFO - PID 9356 | |2;jquery.html;545.7271364317841;557.3701717059722;559.471299621857;569.5102212097597;604.4046614085125;21606;22315.44211394716;22348;22385.544939578915;22501.32496025122;20842.062107205173;21045.158193672272;21063.368098957017;22654.43363915903;24224.515509689794;6521.119648847404;6619.702113404885;6632.966835165817;6643.900341494881;6701.061851340816;6530.412262896339;6638.701258443358;6645.33546645334;6645.93354066458;6649.434798042173;6317.65252911092;6662.700178491955;6670.099536562575;6670.199576050881;6672.733090676367;6601.2738598754095;6615.702293396808;6674.532782705212;6682.031105489726;6692.83267918301;6135.2024236849375;6174.9073763893575;6185.969070154665;6188.690565471708;6190.350013248634;26834.865825670942;27404.177874663794;27793.86103069492;27885.721142788516;27975.860120699468;1972.378700709272;2022.2719820864477;2022.4033909996585;2035.084212104559;2045.4068320187127;18985.33551325711;19383.127759250867;19481.805181948283;19502;19554.608907821905;2432.987835060831;2441.9145329913545;2488.589382751844;2494.750524947509;2514.1828905605566;116542.41728791386;122101.38949305196;122261;128753.7124628751;131174.34412827875;14548.712693863097;14773.197469502975;14784.852151478563;14891.925540372229;15052.473163439226;22590.661140082913;22642.77357226407;22656;22736.65895011576;22791.430214244447;9854.260930430246;9926.801463970825;9965.451900145561;9990.601315815791;10122.898771012344;8995.460272383678;9082.22801061904;9158.542072896462;9198.540072996457;9255.722328330026;8646.013789543147;8892.466452012899;8991;9143.771405714777;9201.171894529525;2745.5058089543672;2750.477409292228;2802.1173330400857;2802.537581299063;2867.5985362049328;961.9374740641836;966.1497881863914;981.8478135888948;987.713563066717;997.1623835977668;19157.13792879324;19368.00099763063;19637.410877673694;19729.309474168334;19799.10904009323;1029.6087486754984;1041.1670663469195;1043.9164866810636;1046.35126221743;1061.5382308695741;10516.684499464875;10745.462726863625;10877.83683244752;10973.561057557608;11019.779604408028;411857;426450;429188;429234;441406;104615;111245;112624;113486;114179;1035.984460233097;1123.7471568897097;1144.8855114488485;1165.889240522132;1174.213277104342;20031.198752049753;20168.991550422714;20367.287144949856;20377.184912603923;20493.2827351042;54673.45326546684;55442;56182.43817561772;56305.71847140738;57339.42660573341;69562;69692.65153674199;69894;70520.64739676268;71191.28808712054;14059.648508786953;14255.572332830032;14305.356258968579;14455.349509271897;14491.130532168103;21858.89070554637;22173.33479995603;22279.77720222777;22401.887990559942;22403.32790016302;16873.91563042177;17078.829211707725;17121.486355409357;17148.914255428645;17203.22585483681;15368.308426120628;15570.610734731723;15983.200839958188;16520.173991300147;16811.41160059392;18239.452816415527;18425.631487370447;18690.81309186945;18933.715994259823;18966.336178233694;14265.714685706434;14281.214533200773;14282.642933926734;14291;14301.856981430052;16910;17857.642847142724;18069.638607228044;18203;18216 16:08:18 INFO - PID 9356 | |3;mootools.html;1340.5894606743814;1407.3385508810854;1411.9717605647882;1416.0370947755523;1417.5180438650857;14518.34667439965;14904.701905961902;14957.850421495781;15002.699946001101;15098;14748.6312842179;14868.182249976247;14938.925305373485;15041.247937603104;15050.397984080651;2636.459525797207;2723.4553089382102;2730.5085084684747;2733.098077634376;2737.904173353933;2695.4474332761833;2729.5905614157937;2729.959050614242;2732.9180124596296;2733.5762956741637;2631.5394805908986;2655.2167110702294;2684.650995370609;2686.6507354043947;2689.341111427703;2550.3241640965066;2552.1067626330823;2563.846169229843;2564.6794150731157;2566.3199252198183;2559.641650168978;2584.3151564835334;2595.5976823592187;2602.349412646838;2609;14615.926920365437;14779.33492992807;14846.406143754237;14855.925720371437;15000.024998375071;7502.249775022508;7741.148473667892;7741.922580774177;7759.301662850314;7793.1817159198035;5032.144535428986;5178.637495375326;5230.738463076831;5279.44565820591;5289.444608316148;1943.3004118517322;1965.5479239774788;1991.3926252492984;1995.7405537280206;2009.1159889648509;23305.533889322287;23863.284101476984;24143;24143.75856241433;26064.218073457636;6756;6919.515633905578;6977.162740471176;7053.92946070543;7079.256678048834;20295.594088118156;20420.38738837837;20449.386518404473;20468.69296960547;20496;7691.346235570039;7702.9229707703325;7797.41519386037;7946;8099.352051835839;6980.197277313073;7086.539374940612;7094.538854974513;7104.11198600175;7353;5587.69267690281;5609.382967873531;5675.829725108253;5683.857903552444;5771.567132465082;1733.7052701040852;1740.4604572582539;1742.7103943082027;1749.6500699860078;1767.0457952705651;881.7266647339345;923.7782932096383;929.0709290709291;932.9580168892417;933.4912472702393;7651.234876512415;7757.9224207758325;7770.689172433039;7855.489393189425;7875.803104922423;720.7333286683855;726.201178703422;729.748236858283;733.4279262175405;743.4312750745684;4706.976465117721;4780.617550595944;4807.18277892759;4839.7096174228955;4839.733814640229;20734.274300399415;21179.152833886474;21359.14563417477;21592.568148637252;21597.136114555557;20110.49723756918;20674.276400325918;21198.576028479652;21295.148194072062;21325.146994120372;10590.046895779264;10705.732356690989;10718.464076796128;10722.624708135178;10801;8246;8308.210719981593;8348.707795227381;8426;8429.915700843158;18166.636667266317;18169.727454088465;18254.36109736153;18344.266229350676;19227.807721922603;18671.71992420142;19096.331628392938;19121.426357209308;19763.80236197677;19938.501537461678;11521.884781152416;11658.475368608264;11683.824742628698;11706.70733231676;11721.062315014608;13668.248246346546;13724.176549407068;13732.86267137316;13746.312684365941;13868.098573592684;11130.554777808797;11442.427878606202;11533.480993355151;11622.70943226426;11770;11036.834447483134;11108.222424430212;11204.775904482029;11298.09615230796;11356.205065645325;9591.616335346507;9600.90399096028;9752.512374381395;9783.0706082922;9859.605615775576;7013.509054366349;7042.225355211025;7117.67970441321;7172.175199852084;7273.599952002691;7050.189228238613;7221.602811845399;7287.927120728937;7308.8903666443985;7325.450591205575 16:08:18 INFO - PID 9356 | |4;prototype.html;492.9957936171034;504.4185886131443;518.3727689495711;520.3313741841735;524.404800551374;6041.214642096526;6098.207233059701;6141.385861413856;6142.140100385949;6208.503319734422;5993.700314984255;6082.391760823915;6111.572189946705;6147.354527774586;6179.845503862406;5128.897422051576;5132.743362831853;5149.356330458692;5152.716600586966;5174.301469301652;5079.517445842639;5082.79668813247;5092.414372347191;5125.231215317714;5163.251328557357;4668.276417155329;4824.758762061901;4860.829870954517;4951.826686065989;5042.218456139304;4781.880452988668;4884.853454396374;4888.560029597354;4895.926561101586;4910.8772280693265;4350.564943505656;4355.869323920288;4390.934135987963;4406.625436837872;4424.203643344197;5741.770329186869;5875.059990401515;5912.793052243151;5952.851178720523;5965.642061476326;5441.374241962186;5513.9448605513835;5549.112142057292;5577.231467095656;5586.608937374385;6324.209473815773;6326.746930122789;6362.809115726535;6365.745370185187;6461.160049193575;4479.3281007848755;4662.207424737769;4685.390899183119;4738.6209103272;4795.184818580815;6489.253735820346;6499.935000649933;6562.475001999828;6567.4746020318735;6739.460843132538;4855.5387238212315;4859.392575928009;4864.683795553277;4877.414710234794;4889.45982015664;4945.862451636144;4974.801007959641;4986.875328116754;4989.126902791997;5000.4999500050435;5488.835334939959;5504.889902201933;5556.305461817273;5569.721513924287;5583.330000399978;6372.681365931778;6451.516136289703;6464.967675161594;6506.837329066717;6508.349165083454;1707.9146042697816;1726.4389073551147;1728.5073753980055;1732.6361464092429;1744.790625124993;1144.8797876223043;1182.532899504708;1186.4542310537076;1217.514632148777;1220.6216073017392;5567.218490083134;5643.717814109278;5649.180868774005;5649.322081350264;5682.914756278659;6498.935010649928;6534.901976470357;6572.14562106923;6606.174228221473;6649.534532582769;5584.190292407578;5602;5611.466910643482;5639.403885833033;5649.745761440663;3266.738660907122;3418.6239513653477;3430.776999495025;3444.156181735491;3500.5274287971306;6319.684015799192;6400.455961243347;6416.294207637156;6432.292447830735;6483.546151769331;6507.219133703986;6528.2166140062545;6742.460603151736;6752.210664173899;6773.221079575912;6169.809717550294;6357.872842543124;6374;6386.584871983307;6443.774467893789;6447.774327898501;6468.2561505427475;6585.967070164618;6617.669116544057;6627.3372662733345;6424.839379015562;6431.710573024318;6472.773452929125;6551.705173267119;6577.079208910852;6336.96831515858;6364.777232797015;6382;6388.233411990591;6524.771632992823;4893.632977526629;4895.755212239445;4910.140725373117;4926.901461970812;4952.752362381939;6350.428076628973;6528.37980391862;6528.706208220547;6609.933900661124;6735.932640673531;6132.969335153296;6317.399847014526;6343.52423568225;6346.90479642815;6408;6284.371562843679;6287.182666253358;6306.621602703853;6328.8165582873835;6374.617522948638;5942.1978032965;6125.693715314305;6221.3156552780065;6236.719347629277;6251.718672659832;6379.2344918608205;6411.070394792822;6424.325445828307;6433.485321174201;6440.8389790255615;6124.816255512342;6146.248319228998;6195.721192546436;6213.65824879636;6251.249850018027 16:08:18 INFO - PID 9356 | |5;yui.html;877.4340550345028;923.709031655029;924.8150369926012;929.7164364868712;938.3290946972916;19167.520811979706;19181.52046198842;19911.30310439135;19954.501137471554;20005.099770510325;18935.52661183471;19158.71261931072;19348.613027739437;19356.22575096998;19387.32144374947;946.342292106986;947.5688561704408;949.9572519236616;955.2358113509199;956.6938579654513;927.5455027036737;953.7853982853837;970.6554173268494;972.6595691507987;979.4270351844144;1021.1575450253533;1025.9384436933772;1031.6079889641962;1032.6695457453618;1034.7309699478114;1018.6994836523206;1022.1822541966471;1023.0281232828843;1023.7798873242251;1026.3687831983352;703.525120543631;714.5462631229155;723.8805597076476;731.4696844787486;760.3347071312602;20494.897525512428;20723.171073157057;20739.688904666444;20741.27405540814;20752.273670421462;21559.24542641;21612.783872161235;22693.205737799097;22942.311730648107;22955.77044229553;16297.185140743033;16709.331626734915;16827.074510901897;16961.406350777663;17037.148142592945;3791.75353602015;3826.693864490834;3835.060410199491;3835.520559930009;3836.136869204435;138910;141505.29247353697;142679;144427;145036;16070.43753468623;16410.343586256655;16414.425495107615;16494.17529123539;16585.751213731804;30609.540856887164;30815.68882333834;31985;32265.51601725976;32274.031779046665;8522.488650680854;8577.485350878967;8601.31189504838;8612.655493780305;8638.352123590756;4325.264705000158;4439.800208990603;4502.482214545304;4505.909881802411;4542.931856022163;5311;5421.701806400607;5431.701256430934;5464.508194262536;5477.123660214346;984.0749695286407;1013.7465633591602;1030.2324768047852;1030.299396410433;1032.5869652139204;667.7629441548278;680.3332733920687;689.1557841643946;704.6547191876047;705.1925545250659;10206.285560010729;10295.330803497749;10366.429846358522;10650.254482186327;10877.456127193609;692.2904023376079;697.6581475077277;711.1466240511407;718.3319512853096;719.7768691705588;5203.245529398216;5264.447233040552;5344.35867695879;5389.326334208224;5485.122380419114;123246;124326.37836810759;124718;125520;127046;99700;111518;112453.6263912084;112790;114158.4292078551;93506.53246733722;98618.50690746644;100312.49843750881;100576;100981.49509252554;10736.141108711128;10977.3962432067;10978.176636752436;11062.557497700001;11362;42675.57324426716;42869;43865.34201986909;44196;44211.55788442074;55231;55287.44712552823;56145.43854561402;56771.71614142068;57714.134287986555;16009.199540022719;16141.273642685872;16188.838111619205;16252.918735406247;16540.83459165393;17813.46559603214;17829.821701782817;17884.642307153525;17939.641207176046;18017.729734053737;16262.756058658893;16351.59121021984;16470.917645411697;16559.66880662356;16771.832281677027;16812.57968550796;16889.577760556083;16979.23593438323;16990.83009169942;17011.48965531036;15182.848171518144;15245.771313430529;15474.922625386802;15509.457168999032;15616.609584760472;10767.3539587625;10871.184661150288;10892.673219803419;11323.377214253294;11326.263792853439;12124.211926224767;12383.380830958597;12441.315727635067;12486.750264994831;12632.74734505323 16:08:18 INFO - PID 9356 | __end_tp_report 16:08:18 INFO - PID 9356 | __start_cc_report 16:08:18 INFO - PID 9356 | _x_x_mozilla_cycle_collect,362 16:08:18 INFO - PID 9356 | __end_cc_report 16:08:18 INFO - PID 9356 | __startTimestamp1504825698294__endTimestamp 16:08:18 INFO - PID 9356 | 16:08:18 INFO - PID 9356 | ------- Summary: start ------- 16:08:18 INFO - PID 9356 | Number of tests: 6 16:08:18 INFO - PID 9356 | 16:08:18 INFO - PID 9356 | [#0] dojo.html Cycles:165 Average:6091.00 Median:6322.24 stddev:715.92 (11.3%) stddev-sans-first:678.37 16:08:18 INFO - PID 9356 | Values: 3092.4 3121.6 3127.5 3132.5 3136.8 6119.1 6202.3 6203.1 6233.1 6267.8 6139.8 6140.8 6141.5 6143.1 6168.1 5685.3 5703.6 5712.6 5719.0 5726.4 5742.2 5752.1 5762.8 5802.9 5816.9 5677.4 5718.1 5734.9 5740.8 5740.8 5560.1 5665.3 5788.0 5804.9 5845.8 5644.3 5658.2 5683.2 5696.9 5715.7 6155.4 6193.8 6195.8 6281.9 6322.2 6497.3 6498.1 6593.2 6594.9 6728.2 6517.9 6537.4 6541.0 6553.5 6553.9 6033.9 6077.0 6079.7 6135.1 6225.1 6726.9 6833.4 6856.4 6910.0 6918.3 6451.2 6463.3 6528.8 6557.8 6587.5 6461.9 6543.8 6609.2 6706.8 6744.7 6518.1 6545.1 6548.2 6551.0 6562.2 6547.2 6555.8 6578.2 6594.5 6611.0 5247.1 5255.7 5295.2 5311.6 5329.9 4586.7 4600.9 4627.1 4640.9 4705.3 5638.8 5723.9 5724.6 5725.9 5729.2 6498.3 6553.1 6570.5 6597.6 6598.4 5131.2 5629.7 5696.5 5751.9 5752.6 5707.3 5719.2 5772.6 5800.9 5820.4 6499.3 6517.8 6594.3 6601.1 6606.0 6640.9 6651.8 6720.4 6727.7 6734.5 6316.6 6439.4 6446.8 6452.3 6454.1 6509.9 6515.6 6534.9 6542.2 6547.1 6561.4 6568.9 6573.8 6619.1 6650.4 6530.1 6532.4 6586.5 6591.0 6599.9 6477.6 6487.8 6494.9 6579.1 6582.1 6120.6 6271.6 6409.5 6473.0 6485.4 6363.6 6374.6 6380.0 6399.2 6432.9 6049.9 6145.5 6153.1 6210.8 6219.7 16:08:18 INFO - PID 9356 | 16:08:18 INFO - PID 9356 | [#1] ext.html Cycles:180 Average:37620.86 Median:3564.20 stddev:52989.55 (1486.7%) stddev-sans-first:53065.81 16:08:18 INFO - PID 9356 | Values: 734.8 747.2 748.0 749.1 749.3 39749.0 40496.6 40589.8 43396.3 43800.0 38529.6 38538.4 38573.2 38591.6 38866.2 6873.4 6993.0 7023.2 7032.2 7061.6 7633.8 7776.5 7813.6 7823.4 7860.8 7769.9 7824.1 7842.1 7865.3 7886.0 7720.5 7817.8 7841.1 8018.8 8018.9 7805.7 7864.1 7873.1 7874.1 7889.3 38570.8 38854.2 38879.0 38967.2 39167.0 3536.2 3555.9 3572.5 3580.8 3581.0 32241.2 32322.4 32389.8 32395.5 32414.0 5223.4 5223.7 5224.5 5247.8 5267.4 189528.0 189787.0 190148.0 190154.0 190163.0 48988.5 49020.0 49032.8 49372.0 49689.0 46037.8 53156.5 53719.7 53784.0 53968.5 16165.5 16177.4 16276.0 16356.9 16364.3 13134.7 13232.3 13246.1 13263.2 13271.8 14327.4 14542.0 14613.8 14736.2 14818.2 5729.0 5827.1 5954.2 5997.4 6012.4 1020.2 1034.5 1038.3 1044.4 1049.2 23399.5 23545.4 23635.9 23725.8 23803.3 1114.7 1122.3 1136.0 1140.0 1142.8 12374.3 12510.7 12582.7 12682.8 12694.4 228750.0 228815.0 228890.0 228996.0 229754.0 55506.4 55528.4 55580.0 55941.7 56313.7 1430.8 1463.0 1466.9 1475.2 1475.5 22543.7 22990.8 22996.4 23179.2 23446.0 38320.4 38412.8 38507.4 38584.2 38595.8 191516.0 191817.0 192116.0 192119.0 194361.0 31431.4 31570.7 32235.7 34964.7 36154.0 43748.0 44198.3 44336.0 44389.8 44515.6 31936.7 32152.0 32635.5 32806.5 32926.5 32213.4 32817.2 32819.0 32943.5 33040.7 36004.3 36117.3 36154.8 36590.6 36631.9 31467.4 31673.7 32120.4 32166.8 32353.4 23754.2 23904.9 24031.4 24313.9 24475.0 16:08:18 INFO - PID 9356 | 16:08:18 INFO - PID 9356 | [#2] jquery.html Cycles:180 Average:32242.02 Median:22393.72 stddev:72427.33 (323.4%) stddev-sans-first:72591.41 16:08:18 INFO - PID 9356 | Values: 545.7 557.4 559.5 569.5 604.4 21606.0 22315.4 22348.0 22385.5 22501.3 20842.1 21045.2 21063.4 22654.4 24224.5 6521.1 6619.7 6633.0 6643.9 6701.1 6530.4 6638.7 6645.3 6645.9 6649.4 6317.7 6662.7 6670.1 6670.2 6672.7 6601.3 6615.7 6674.5 6682.0 6692.8 6135.2 6174.9 6186.0 6188.7 6190.4 26834.9 27404.2 27793.9 27885.7 27975.9 1972.4 2022.3 2022.4 2035.1 2045.4 18985.3 19383.1 19481.8 19502.0 19554.6 2433.0 2441.9 2488.6 2494.8 2514.2 116542.4 122101.4 122261.0 128753.7 131174.3 14548.7 14773.2 14784.9 14891.9 15052.5 22590.7 22642.8 22656.0 22736.7 22791.4 9854.3 9926.8 9965.5 9990.6 10122.9 8995.5 9082.2 9158.5 9198.5 9255.7 8646.0 8892.5 8991.0 9143.8 9201.2 2745.5 2750.5 2802.1 2802.5 2867.6 961.9 966.1 981.8 987.7 997.2 19157.1 19368.0 19637.4 19729.3 19799.1 1029.6 1041.2 1043.9 1046.4 1061.5 10516.7 10745.5 10877.8 10973.6 11019.8 411857.0 426450.0 429188.0 429234.0 441406.0 104615.0 111245.0 112624.0 113486.0 114179.0 1036.0 1123.7 1144.9 1165.9 1174.2 20031.2 20169.0 20367.3 20377.2 20493.3 54673.5 55442.0 56182.4 56305.7 57339.4 69562.0 69692.7 69894.0 70520.6 71191.3 14059.6 14255.6 14305.4 14455.3 14491.1 21858.9 22173.3 22279.8 22401.9 22403.3 16873.9 17078.8 17121.5 17148.9 17203.2 15368.3 15570.6 15983.2 16520.2 16811.4 18239.5 18425.6 18690.8 18933.7 18966.3 14265.7 14281.2 14282.6 14291.0 14301.9 16910.0 17857.6 18069.6 18203.0 18216.0 16:08:18 INFO - PID 9356 | 16:08:18 INFO - PID 9356 | [#3] mootools.html Cycles:180 Average:9269.43 Median:2589.96 stddev:6596.71 (254.7%) stddev-sans-first:6588.32 16:08:18 INFO - PID 9356 | Values: 1340.6 1407.3 1412.0 1416.0 1417.5 14518.3 14904.7 14957.9 15002.7 15098.0 14748.6 14868.2 14938.9 15041.2 15050.4 2636.5 2723.5 2730.5 2733.1 2737.9 2695.4 2729.6 2730.0 2732.9 2733.6 2631.5 2655.2 2684.7 2686.7 2689.3 2550.3 2552.1 2563.8 2564.7 2566.3 2559.6 2584.3 2595.6 2602.3 2609.0 14615.9 14779.3 14846.4 14855.9 15000.0 7502.2 7741.1 7741.9 7759.3 7793.2 5032.1 5178.6 5230.7 5279.4 5289.4 1943.3 1965.5 1991.4 1995.7 2009.1 23305.5 23863.3 24143.0 24143.8 26064.2 6756.0 6919.5 6977.2 7053.9 7079.3 20295.6 20420.4 20449.4 20468.7 20496.0 7691.3 7702.9 7797.4 7946.0 8099.4 6980.2 7086.5 7094.5 7104.1 7353.0 5587.7 5609.4 5675.8 5683.9 5771.6 1733.7 1740.5 1742.7 1749.7 1767.0 881.7 923.8 929.1 933.0 933.5 7651.2 7757.9 7770.7 7855.5 7875.8 720.7 726.2 729.7 733.4 743.4 4707.0 4780.6 4807.2 4839.7 4839.7 20734.3 21179.2 21359.1 21592.6 21597.1 20110.5 20674.3 21198.6 21295.1 21325.1 10590.0 10705.7 10718.5 10722.6 10801.0 8246.0 8308.2 8348.7 8426.0 8429.9 18166.6 18169.7 18254.4 18344.3 19227.8 18671.7 19096.3 19121.4 19763.8 19938.5 11521.9 11658.5 11683.8 11706.7 11721.1 13668.2 13724.2 13732.9 13746.3 13868.1 11130.6 11442.4 11533.5 11622.7 11770.0 11036.8 11108.2 11204.8 11298.1 11356.2 9591.6 9600.9 9752.5 9783.1 9859.6 7013.5 7042.2 7117.7 7172.2 7273.6 7050.2 7221.6 7287.9 7308.9 7325.5 16:08:18 INFO - PID 9356 | 16:08:18 INFO - PID 9356 | [#4] prototype.html Cycles:180 Average:5385.55 Median:5979.67 stddev:1500.48 (25.1%) stddev-sans-first:1459.07 16:08:18 INFO - PID 9356 | Values: 493.0 504.4 518.4 520.3 524.4 6041.2 6098.2 6141.4 6142.1 6208.5 5993.7 6082.4 6111.6 6147.4 6179.8 5128.9 5132.7 5149.4 5152.7 5174.3 5079.5 5082.8 5092.4 5125.2 5163.3 4668.3 4824.8 4860.8 4951.8 5042.2 4781.9 4884.9 4888.6 4895.9 4910.9 4350.6 4355.9 4390.9 4406.6 4424.2 5741.8 5875.1 5912.8 5952.9 5965.6 5441.4 5513.9 5549.1 5577.2 5586.6 6324.2 6326.7 6362.8 6365.7 6461.2 4479.3 4662.2 4685.4 4738.6 4795.2 6489.3 6499.9 6562.5 6567.5 6739.5 4855.5 4859.4 4864.7 4877.4 4889.5 4945.9 4974.8 4986.9 4989.1 5000.5 5488.8 5504.9 5556.3 5569.7 5583.3 6372.7 6451.5 6465.0 6506.8 6508.3 1707.9 1726.4 1728.5 1732.6 1744.8 1144.9 1182.5 1186.5 1217.5 1220.6 5567.2 5643.7 5649.2 5649.3 5682.9 6498.9 6534.9 6572.1 6606.2 6649.5 5584.2 5602.0 5611.5 5639.4 5649.7 3266.7 3418.6 3430.8 3444.2 3500.5 6319.7 6400.5 6416.3 6432.3 6483.5 6507.2 6528.2 6742.5 6752.2 6773.2 6169.8 6357.9 6374.0 6386.6 6443.8 6447.8 6468.3 6586.0 6617.7 6627.3 6424.8 6431.7 6472.8 6551.7 6577.1 6337.0 6364.8 6382.0 6388.2 6524.8 4893.6 4895.8 4910.1 4926.9 4952.8 6350.4 6528.4 6528.7 6609.9 6735.9 6133.0 6317.4 6343.5 6346.9 6408.0 6284.4 6287.2 6306.6 6328.8 6374.6 5942.2 6125.7 6221.3 6236.7 6251.7 6379.2 6411.1 6424.3 6433.5 6440.8 6124.8 6146.2 6195.7 6213.7 6251.2 16:08:18 INFO - PID 9356 | 16:08:18 INFO - PID 9356 | [#5] yui.html Cycles:180 Average:24623.31 Median:19352.42 stddev:35995.42 (186.0%) stddev-sans-first:36052.23 16:08:18 INFO - PID 9356 | Values: 877.4 923.7 924.8 929.7 938.3 19167.5 19181.5 19911.3 19954.5 20005.1 18935.5 19158.7 19348.6 19356.2 19387.3 946.3 947.6 950.0 955.2 956.7 927.5 953.8 970.7 972.7 979.4 1021.2 1025.9 1031.6 1032.7 1034.7 1018.7 1022.2 1023.0 1023.8 1026.4 703.5 714.5 723.9 731.5 760.3 20494.9 20723.2 20739.7 20741.3 20752.3 21559.2 21612.8 22693.2 22942.3 22955.8 16297.2 16709.3 16827.1 16961.4 17037.1 3791.8 3826.7 3835.1 3835.5 3836.1 138910.0 141505.3 142679.0 144427.0 145036.0 16070.4 16410.3 16414.4 16494.2 16585.8 30609.5 30815.7 31985.0 32265.5 32274.0 8522.5 8577.5 8601.3 8612.7 8638.4 4325.3 4439.8 4502.5 4505.9 4542.9 5311.0 5421.7 5431.7 5464.5 5477.1 984.1 1013.7 1030.2 1030.3 1032.6 667.8 680.3 689.2 704.7 705.2 10206.3 10295.3 10366.4 10650.3 10877.5 692.3 697.7 711.1 718.3 719.8 5203.2 5264.4 5344.4 5389.3 5485.1 123246.0 124326.4 124718.0 125520.0 127046.0 99700.0 111518.0 112453.6 112790.0 114158.4 93506.5 98618.5 100312.5 100576.0 100981.5 10736.1 10977.4 10978.2 11062.6 11362.0 42675.6 42869.0 43865.3 44196.0 44211.6 55231.0 55287.4 56145.4 56771.7 57714.1 16009.2 16141.3 16188.8 16252.9 16540.8 17813.5 17829.8 17884.6 17939.6 18017.7 16262.8 16351.6 16470.9 16559.7 16771.8 16812.6 16889.6 16979.2 16990.8 17011.5 15182.8 15245.8 15474.9 15509.5 15616.6 10767.4 10871.2 10892.7 11323.4 11326.3 12124.2 12383.4 12441.3 12486.8 12632.7 16:08:18 INFO - PID 9356 | -------- Summary: end -------- 16:08:18 INFO - PID 9356 | 16:08:19 INFO - TEST-INFO | 9356: exit 0 16:08:19 INFO - TEST-OK | dromaeo_css | took 1112795ms 16:08:19 INFO - TEST-START | kraken 16:08:19 INFO - Initialising browser for kraken test... 16:08:19 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55349/getInfo.html -profile /tmp/tmp3Vp9hz/profile 16:08:19 INFO - TEST-INFO | started process 9750 (/builds/slave/test/build/application/firefox/firefox http://localhost:55349/getInfo.html) 16:08:24 INFO - TEST-INFO | 9750: exit 0 16:08:24 INFO - Browser initialized. 16:08:24 INFO - Running cycle 1/1 for kraken test... 16:08:24 INFO - Using env: {'DISPLAY': ':0', 16:08:24 INFO - 'HOME': '/home/cltbld', 16:08:24 INFO - 'JSGC_DISABLE_POISONING': '1', 16:08:24 INFO - 'LANG': 'en_US.UTF-8', 16:08:24 INFO - 'LANGUAGE': 'en_US:en', 16:08:24 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:08:24 INFO - 'LOGNAME': 'cltbld', 16:08:24 INFO - 'MAIL': '/var/mail/cltbld', 16:08:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:08:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:08:24 INFO - 'MOZ_ACCELERATED': '1', 16:08:24 INFO - 'MOZ_CRASHREPORTER': '1', 16:08:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:24 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:08:24 INFO - 'MOZ_NO_REMOTE': '1', 16:08:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:08:24 INFO - 'MOZ_WEBRENDER': '1', 16:08:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:24 INFO - 'NO_EM_RESTART': '1', 16:08:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:24 INFO - 'PWD': '/builds/slave/test', 16:08:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:08:24 INFO - 'RUST_BACKTRACE': 'full', 16:08:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:08:24 INFO - 'SHELL': '/bin/bash', 16:08:24 INFO - 'SHLVL': '1', 16:08:24 INFO - 'TERM': 'linux', 16:08:24 INFO - 'TMOUT': '86400', 16:08:24 INFO - 'USER': 'cltbld', 16:08:24 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976', 16:08:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:24 INFO - '_': '/tools/buildbot/bin/python'} 16:08:24 INFO - TEST-INFO | started process 9956 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3Vp9hz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:08:25 INFO - PID 9956 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:08:25 INFO - PID 9956 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:08:26 INFO - PID 9956 | 16:08:26 INFO - PID 9956 | (/builds/slave/test/build/application/firefox/firefox:10018): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:08:26 INFO - PID 9956 | 16:08:36 INFO - PID 9956 | RSS: Main: 212467712 16:08:36 INFO - PID 9956 | 16:08:39 INFO - PID 9956 | Cycle 1(1): loaded http://localhost:55349/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55349/tests/kraken/driver.html?testName=audio-beat-detection) 16:08:40 INFO - PID 9956 | RSS: Main: 219357184 16:08:40 INFO - PID 9956 | 16:08:42 INFO - PID 9956 | Cycle 1(1): loaded http://localhost:55349/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55349/tests/kraken/driver.html?testName=audio-dft) 16:08:43 INFO - PID 9956 | RSS: Main: 220147712 16:08:43 INFO - PID 9956 | 16:08:46 INFO - PID 9956 | Cycle 1(1): loaded http://localhost:55349/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55349/tests/kraken/driver.html?testName=audio-fft) 16:08:47 INFO - PID 9956 | RSS: Main: 215183360 16:08:47 INFO - PID 9956 | 16:08:49 INFO - PID 9956 | Cycle 1(1): loaded http://localhost:55349/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55349/tests/kraken/driver.html?testName=audio-oscillator) 16:08:49 INFO - PID 9956 | RSS: Main: 214102016 16:08:49 INFO - PID 9956 | 16:08:52 INFO - PID 9956 | Cycle 1(1): loaded http://localhost:55349/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55349/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:08:52 INFO - PID 9956 | RSS: Main: 216121344 16:08:52 INFO - PID 9956 | 16:08:56 INFO - PID 9956 | Cycle 1(1): loaded http://localhost:55349/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55349/tests/kraken/driver.html?testName=imaging-darkroom) 16:08:56 INFO - PID 9956 | RSS: Main: 218439680 16:08:56 INFO - PID 9956 | 16:09:00 INFO - PID 9956 | Cycle 1(1): loaded http://localhost:55349/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55349/tests/kraken/driver.html?testName=imaging-desaturate) 16:09:00 INFO - PID 9956 | RSS: Main: 216961024 16:09:00 INFO - PID 9956 | 16:09:03 INFO - PID 9956 | Cycle 1(1): loaded http://localhost:55349/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55349/tests/kraken/driver.html?testName=json-parse-financial) 16:09:04 INFO - PID 9956 | RSS: Main: 218939392 16:09:04 INFO - PID 9956 | 16:09:06 INFO - PID 9956 | Cycle 1(1): loaded http://localhost:55349/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55349/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:09:06 INFO - PID 9956 | RSS: Main: 218193920 16:09:06 INFO - PID 9956 | 16:09:08 INFO - PID 9956 | Cycle 1(1): loaded http://localhost:55349/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55349/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:09:09 INFO - PID 9956 | RSS: Main: 215048192 16:09:09 INFO - PID 9956 | 16:09:11 INFO - PID 9956 | Cycle 1(1): loaded http://localhost:55349/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55349/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:09:12 INFO - PID 9956 | RSS: Main: 214540288 16:09:12 INFO - PID 9956 | 16:09:14 INFO - PID 9956 | Cycle 1(1): loaded http://localhost:55349/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55349/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:09:15 INFO - PID 9956 | RSS: Main: 216289280 16:09:15 INFO - PID 9956 | 16:09:18 INFO - PID 9956 | Cycle 1(1): loaded http://localhost:55349/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55349/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:09:19 INFO - PID 9956 | RSS: Main: 215105536 16:09:19 INFO - PID 9956 | 16:09:20 INFO - PID 9956 | Cycle 1(1): loaded http://localhost:55349/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55349/tests/kraken/driver.html?testName=ai-astar) 16:09:21 INFO - PID 9956 | RSS: Main: 218099712 16:09:21 INFO - PID 9956 | 16:09:21 INFO - PID 9956 | __start_tp_report 16:09:21 INFO - PID 9956 | _x_x_mozilla_page_load 16:09:21 INFO - PID 9956 | _x_x_mozilla_page_load_details 16:09:21 INFO - PID 9956 | |i|pagename|runs| 16:09:21 INFO - PID 9956 | |0;ai-astar;119;116;112;115;111;115;108;109;113;111 16:09:21 INFO - PID 9956 | |1;audio-beat-detection;122;117;119;127;121;122;123;143;126;117 16:09:21 INFO - PID 9956 | |2;audio-dft;172;175;163;164;168;174;171;170;172;162 16:09:21 INFO - PID 9956 | |3;audio-fft;76;74;75;78;79;74;74;80;79;83 16:09:21 INFO - PID 9956 | |4;audio-oscillator;81;85;79;81;84;84;79;82;80;86 16:09:21 INFO - PID 9956 | |5;imaging-gaussian-blur;115;116;121;121;117;124;114;115;114;115 16:09:21 INFO - PID 9956 | |6;imaging-darkroom;148;154;151;150;150;150;149;149;151;155 16:09:21 INFO - PID 9956 | |7;imaging-desaturate;100;104;94;95;95;95;95;101;94;96 16:09:21 INFO - PID 9956 | |8;json-parse-financial;58;52;53;53;54;56;54;53;53;52 16:09:21 INFO - PID 9956 | |9;json-stringify-tinderbox;38;47;38;46;43;48;49;39;46;43 16:09:21 INFO - PID 9956 | |10;stanford-crypto-aes;78;90;83;86;81;77;88;83;80;80 16:09:21 INFO - PID 9956 | |11;stanford-crypto-ccm;135;131;133;120;119;131;120;123;118;134 16:09:21 INFO - PID 9956 | |12;stanford-crypto-pbkdf2;182;179;173;171;170;168;173;175;173;173 16:09:21 INFO - PID 9956 | |13;stanford-crypto-sha256-iterative;68;73;64;63;68;73;76;69;64;64 16:09:21 INFO - PID 9956 | __end_tp_report 16:09:21 INFO - PID 9956 | __start_cc_report 16:09:21 INFO - PID 9956 | _x_x_mozilla_cycle_collect,935 16:09:21 INFO - PID 9956 | __end_cc_report 16:09:21 INFO - PID 9956 | __startTimestamp1504825761158__endTimestamp 16:09:21 INFO - PID 9956 | 16:09:21 INFO - PID 9956 | ------- Summary: start ------- 16:09:21 INFO - PID 9956 | Number of tests: 14 16:09:21 INFO - PID 9956 | 16:09:21 INFO - PID 9956 | [#0] ai-astar Cycles:10 Average:112.90 Median:114.00 stddev:3.38 (3.0%) stddev-sans-first:2.77 16:09:21 INFO - PID 9956 | Values: 119.0 116.0 112.0 115.0 111.0 115.0 108.0 109.0 113.0 111.0 16:09:21 INFO - PID 9956 | 16:09:21 INFO - PID 9956 | [#1] audio-beat-detection Cycles:10 Average:123.70 Median:122.50 stddev:7.56 (6.2%) stddev-sans-first:7.99 16:09:21 INFO - PID 9956 | Values: 122.0 117.0 119.0 127.0 121.0 122.0 123.0 143.0 126.0 117.0 16:09:21 INFO - PID 9956 | 16:09:21 INFO - PID 9956 | [#2] audio-dft Cycles:10 Average:169.10 Median:171.50 stddev:4.65 (2.7%) stddev-sans-first:4.82 16:09:21 INFO - PID 9956 | Values: 172.0 175.0 163.0 164.0 168.0 174.0 171.0 170.0 172.0 162.0 16:09:21 INFO - PID 9956 | 16:09:21 INFO - PID 9956 | [#3] audio-fft Cycles:10 Average:77.20 Median:78.50 stddev:3.08 (3.9%) stddev-sans-first:3.24 16:09:21 INFO - PID 9956 | Values: 76.0 74.0 75.0 78.0 79.0 74.0 74.0 80.0 79.0 83.0 16:09:21 INFO - PID 9956 | 16:09:21 INFO - PID 9956 | [#4] audio-oscillator Cycles:10 Average:82.10 Median:83.00 stddev:2.51 (3.0%) stddev-sans-first:2.64 16:09:21 INFO - PID 9956 | Values: 81.0 85.0 79.0 81.0 84.0 84.0 79.0 82.0 80.0 86.0 16:09:21 INFO - PID 9956 | 16:09:21 INFO - PID 9956 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:116.50 stddev:3.52 (3.0%) stddev-sans-first:3.64 16:09:21 INFO - PID 9956 | Values: 115.0 116.0 121.0 121.0 117.0 124.0 114.0 115.0 114.0 115.0 16:09:21 INFO - PID 9956 | 16:09:21 INFO - PID 9956 | [#6] imaging-darkroom Cycles:10 Average:150.70 Median:150.50 stddev:2.21 (1.5%) stddev-sans-first:2.12 16:09:21 INFO - PID 9956 | Values: 148.0 154.0 151.0 150.0 150.0 150.0 149.0 149.0 151.0 155.0 16:09:21 INFO - PID 9956 | 16:09:21 INFO - PID 9956 | [#7] imaging-desaturate Cycles:10 Average:96.90 Median:95.00 stddev:3.48 (3.7%) stddev-sans-first:3.50 16:09:21 INFO - PID 9956 | Values: 100.0 104.0 94.0 95.0 95.0 95.0 95.0 101.0 94.0 96.0 16:09:21 INFO - PID 9956 | 16:09:21 INFO - PID 9956 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:53.50 stddev:1.87 (3.5%) stddev-sans-first:1.22 16:09:21 INFO - PID 9956 | Values: 58.0 52.0 53.0 53.0 54.0 56.0 54.0 53.0 53.0 52.0 16:09:21 INFO - PID 9956 | 16:09:21 INFO - PID 9956 | [#9] json-stringify-tinderbox Cycles:10 Average:43.70 Median:46.00 stddev:4.16 (9.1%) stddev-sans-first:3.87 16:09:21 INFO - PID 9956 | Values: 38.0 47.0 38.0 46.0 43.0 48.0 49.0 39.0 46.0 43.0 16:09:21 INFO - PID 9956 | 16:09:21 INFO - PID 9956 | [#10] stanford-crypto-aes Cycles:10 Average:82.60 Median:83.00 stddev:4.27 (5.1%) stddev-sans-first:4.20 16:09:21 INFO - PID 9956 | Values: 78.0 90.0 83.0 86.0 81.0 77.0 88.0 83.0 80.0 80.0 16:09:21 INFO - PID 9956 | 16:09:21 INFO - PID 9956 | [#11] stanford-crypto-ccm Cycles:10 Average:126.40 Median:131.00 stddev:6.96 (5.3%) stddev-sans-first:6.65 16:09:21 INFO - PID 9956 | Values: 135.0 131.0 133.0 120.0 119.0 131.0 120.0 123.0 118.0 134.0 16:09:21 INFO - PID 9956 | 16:09:21 INFO - PID 9956 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.70 Median:173.00 stddev:4.14 (2.4%) stddev-sans-first:3.11 16:09:21 INFO - PID 9956 | Values: 182.0 179.0 173.0 171.0 170.0 168.0 173.0 175.0 173.0 173.0 16:09:21 INFO - PID 9956 | 16:09:21 INFO - PID 9956 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.50 stddev:4.57 (6.7%) stddev-sans-first:4.84 16:09:21 INFO - PID 9956 | Values: 68.0 73.0 64.0 63.0 68.0 73.0 76.0 69.0 64.0 64.0 16:09:21 INFO - PID 9956 | -------- Summary: end -------- 16:09:21 INFO - PID 9956 | 16:09:21 INFO - PID 9956 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:09:22 INFO - TEST-INFO | 9956: exit 0 16:09:22 INFO - TEST-OK | kraken | took 62873ms 16:09:22 INFO - SUITE-END | took 1175s 16:09:22 INFO - Completed test suite (00:19:35) 16:09:22 INFO - javascript benchmark 16:09:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8427.245803599839, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3092.381523695261, 3121.5941927549416, 3127.483965145751, 3132.5301204819275, 3136.7647426443014, 6119.143319935201, 6202.3487533808975, 6203.069539569067, 6233.127362169294, 6267.811965641026, 6139.754409823602, 6140.754369825213, 6141.539384546166, 6143.10924915887, 6168.136460895478, 5685.346185188715, 5703.57223208259, 5712.600117991742, 5719.0, 5726.3987281335485, 5742.224799652056, 5752.108423194411, 5762.798302059428, 5802.941970580282, 5816.854578635547, 5677.432256774331, 5718.085106382959, 5734.856628584277, 5740.7703691852275, 5740.827775166752, 5560.110382338807, 5665.291838520185, 5787.971060144714, 5804.883902321951, 5845.766169353221, 5644.266245388073, 5658.207850900836, 5683.175939488751, 5696.914546281809, 5715.714214289269, 6155.44600985909, 6193.845153871144, 6195.845103872394, 6281.87436251277, 6322.241331040259, 6497.252815926182, 6498.122753428274, 6593.241777195636, 6594.901076483857, 6728.192616885956, 6517.902231466532, 6537.378948999837, 6540.967295163541, 6553.508486863504, 6553.901691474632, 6033.879322413527, 6077.0, 6079.6960151992225, 6135.141080248769, 6225.128482012522, 6726.865462690719, 6833.4191593715095, 6856.41720453767, 6910.0, 6918.308169183142, 6451.1936007999, 6463.289038205888, 6528.804135875931, 6557.770478033246, 6587.53887227899, 6461.870762584722, 6543.803685889431, 6609.239937407164, 6706.798796036127, 6744.730210791611, 6518.120053792677, 6545.083688283644, 6548.246951600531, 6551.0, 6562.179727534059, 6547.181602299713, 6555.770548030796, 6578.177727784027, 6594.505412094112, 6611.041398997215, 5247.081760691864, 5255.710935898486, 5295.152775555893, 5311.628186027018, 5329.8934021319355, 4586.656000799953, 4600.884977875513, 4627.074585082997, 4640.930386044212, 4705.341252224691, 5638.830835074954, 5723.8569035774435, 5724.599278050579, 5725.8568535786935, 5729.169270455761, 6498.252700939358, 6553.148090748172, 6570.507211959122, 6597.637129957802, 6598.439132673777, 5131.204663277198, 5629.746661400247, 5696.544276457874, 5751.9424805751405, 5752.568557358131, 5707.258056452634, 5719.227904232958, 5772.595918285764, 5800.9419905800405, 5820.3888591698105, 6499.317571655003, 6517.771877984343, 6594.340565943367, 6601.141851559362, 6606.0, 6640.900386494303, 6651.767188148391, 6720.361565651256, 6727.697253623696, 6734.528582999241, 6316.557840951089, 6439.420452159328, 6446.838829029124, 6452.322506136788, 6454.128692626436, 6509.902351464637, 6515.641639709862, 6534.869302614016, 6542.247641521096, 6547.148870646785, 6561.376669216417, 6568.91612883888, 6573.769918052845, 6619.073329733745, 6650.434713049445, 6530.053142294436, 6532.41208291256, 6586.473082153512, 6591.0, 6599.868002640016, 6477.643729594917, 6487.772927947499, 6494.935050649622, 6579.144711187707, 6582.111414958919, 6120.632762034292, 6271.62370257786, 6409.487241020801, 6472.967635161794, 6485.383888530582, 6363.5545511813725, 6374.617522948638, 6380.0, 6399.200099987502, 6432.903506447314, 6049.909251361145, 6145.477634401126, 6153.138560601609, 6210.844728881814, 6219.720112595034], "value": 6037.521268562912, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.8126227811907, 747.2154238050048, 747.9513831600949, 749.123525475194, 749.2806905370844, 39749.0, 40496.59503404965, 40589.79705101474, 43396.34905476413, 43800.0, 38529.61470385295, 38538.4219236711, 38573.228535429276, 38591.61408385915, 38866.22267554647, 6873.381395674376, 6993.0, 7023.227444981048, 7032.191298000745, 7061.611611361373, 7633.847323053536, 7776.53340799551, 7813.609319534029, 7823.413244006694, 7860.764177074669, 7769.883451748228, 7824.061112666488, 7842.058952925657, 7865.331446827026, 7886.0, 7720.498167619086, 7817.843643127106, 7841.059072911287, 8018.799530011738, 8018.919810801875, 7805.687772489094, 7864.056313242448, 7873.133955264916, 7874.05511338643, 7889.289963903218, 38570.80714596437, 38854.22291554181, 38879.0, 38967.220655587014, 39167.0, 3536.2220311531423, 3555.8933232002823, 3572.499850020999, 3580.8030558319283, 3580.964190358089, 32241.1939701507, 32322.353552929046, 32389.83805080983, 32395.51406728901, 32414.0, 5223.399309079428, 5223.738813059332, 5224.4775522447835, 5247.842564723064, 5267.420583735786, 189528.0, 189787.0, 190148.0, 190154.0, 190163.0, 48988.510114898396, 49020.0, 49032.75483622559, 49372.0, 49689.0, 46037.7698111514, 53156.46843531515, 53719.731401343524, 53784.0, 53968.460315397126, 16165.515034548747, 16177.352905883869, 16276.0, 16356.918215409085, 16364.345426183057, 13134.737305254033, 13232.338383080807, 13246.139000964906, 13263.204207747565, 13271.800922986164, 14327.35526901292, 14542.0, 14613.780793287897, 14736.189509576861, 14818.184999824898, 5728.971355143281, 5827.125931110368, 5954.225950626477, 5997.430244126801, 6012.428819262163, 1020.2348238820884, 1034.4724190662816, 1038.2680210451613, 1044.4255659387306, 1049.2130901823632, 23399.53200935972, 23545.411364715677, 23635.88182059079, 23725.762742372357, 23803.285901422638, 1114.7101753544136, 1122.315387613555, 1135.9545618175346, 1139.9544018239344, 1142.7600203957263, 12374.257544547356, 12510.74978500425, 12582.685432864251, 12682.80975785364, 12694.42875070624, 228750.0, 228815.0, 228890.0, 228996.0, 229754.0, 55506.44493555013, 55528.44471555233, 55580.0, 55941.720291398284, 56313.71843140758, 1430.7996880436747, 1462.9634259143393, 1466.9119852809079, 1475.1886462445611, 1475.4614565683448, 22543.66184507201, 22990.770092298862, 22996.42508937223, 23179.188728394427, 23446.0, 38320.42519362156, 38412.80793596014, 38507.42238866363, 38584.22831543297, 38595.80702096471, 191516.0, 191817.0, 192116.0, 192119.0, 194361.0, 31431.371372571964, 31570.684293156773, 32235.677643224204, 34964.65035349614, 36154.0, 43748.0, 44198.337024944005, 44336.0, 44389.77805110954, 44515.554844451144, 31936.6806331943, 32152.03543893687, 32635.510467343483, 32806.507902381, 32926.50610240896, 32213.35573288568, 32817.179570510925, 32819.0, 32943.50584741279, 33040.66959330376, 36004.27991440209, 36117.27765444729, 36154.8192259037, 36590.63409365872, 36631.901042968755, 31467.370652587277, 31673.683263167073, 32120.35759284848, 32166.83916580402, 32353.35293294168, 23754.168604098773, 23904.88047559751, 24031.399215019763, 24313.878430607732, 24475.0], "value": 17241.442351355174, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [545.7271364317841, 557.3701717059722, 559.471299621857, 569.5102212097597, 604.4046614085125, 21606.0, 22315.44211394716, 22348.0, 22385.544939578915, 22501.32496025122, 20842.062107205173, 21045.158193672272, 21063.368098957017, 22654.43363915903, 24224.515509689794, 6521.119648847404, 6619.702113404885, 6632.966835165817, 6643.900341494881, 6701.061851340816, 6530.412262896339, 6638.701258443358, 6645.33546645334, 6645.93354066458, 6649.434798042173, 6317.65252911092, 6662.700178491955, 6670.099536562575, 6670.199576050881, 6672.733090676367, 6601.2738598754095, 6615.702293396808, 6674.532782705212, 6682.031105489726, 6692.83267918301, 6135.2024236849375, 6174.9073763893575, 6185.969070154665, 6188.690565471708, 6190.350013248634, 26834.865825670942, 27404.177874663794, 27793.86103069492, 27885.721142788516, 27975.860120699468, 1972.378700709272, 2022.2719820864477, 2022.4033909996585, 2035.084212104559, 2045.4068320187127, 18985.33551325711, 19383.127759250867, 19481.805181948283, 19502.0, 19554.608907821905, 2432.987835060831, 2441.9145329913545, 2488.589382751844, 2494.750524947509, 2514.1828905605566, 116542.41728791386, 122101.38949305196, 122261.0, 128753.7124628751, 131174.34412827875, 14548.712693863097, 14773.197469502975, 14784.852151478563, 14891.925540372229, 15052.473163439226, 22590.661140082913, 22642.77357226407, 22656.0, 22736.65895011576, 22791.430214244447, 9854.260930430246, 9926.801463970825, 9965.451900145561, 9990.601315815791, 10122.898771012344, 8995.460272383678, 9082.22801061904, 9158.542072896462, 9198.540072996457, 9255.722328330026, 8646.013789543147, 8892.466452012899, 8991.0, 9143.771405714777, 9201.171894529525, 2745.5058089543672, 2750.477409292228, 2802.1173330400857, 2802.537581299063, 2867.5985362049328, 961.9374740641836, 966.1497881863914, 981.8478135888948, 987.713563066717, 997.1623835977668, 19157.13792879324, 19368.00099763063, 19637.410877673694, 19729.309474168334, 19799.10904009323, 1029.6087486754984, 1041.1670663469195, 1043.9164866810636, 1046.35126221743, 1061.5382308695741, 10516.684499464875, 10745.462726863625, 10877.83683244752, 10973.561057557608, 11019.779604408028, 411857.0, 426450.0, 429188.0, 429234.0, 441406.0, 104615.0, 111245.0, 112624.0, 113486.0, 114179.0, 1035.984460233097, 1123.7471568897097, 1144.8855114488485, 1165.889240522132, 1174.213277104342, 20031.198752049753, 20168.991550422714, 20367.287144949856, 20377.184912603923, 20493.2827351042, 54673.45326546684, 55442.0, 56182.43817561772, 56305.71847140738, 57339.42660573341, 69562.0, 69692.65153674199, 69894.0, 70520.64739676268, 71191.28808712054, 14059.648508786953, 14255.572332830032, 14305.356258968579, 14455.349509271897, 14491.130532168103, 21858.89070554637, 22173.33479995603, 22279.77720222777, 22401.887990559942, 22403.32790016302, 16873.91563042177, 17078.829211707725, 17121.486355409357, 17148.914255428645, 17203.22585483681, 15368.308426120628, 15570.610734731723, 15983.200839958188, 16520.173991300147, 16811.41160059392, 18239.452816415527, 18425.631487370447, 18690.81309186945, 18933.715994259823, 18966.336178233694, 14265.714685706434, 14281.214533200773, 14282.642933926734, 14291.0, 14301.856981430052, 16910.0, 17857.642847142724, 18069.638607228044, 18203.0, 18216.0], "value": 11741.527091698017, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1340.5894606743814, 1407.3385508810854, 1411.9717605647882, 1416.0370947755523, 1417.5180438650857, 14518.34667439965, 14904.701905961902, 14957.850421495781, 15002.699946001101, 15098.0, 14748.6312842179, 14868.182249976247, 14938.925305373485, 15041.247937603104, 15050.397984080651, 2636.459525797207, 2723.4553089382102, 2730.5085084684747, 2733.098077634376, 2737.904173353933, 2695.4474332761833, 2729.5905614157937, 2729.959050614242, 2732.9180124596296, 2733.5762956741637, 2631.5394805908986, 2655.2167110702294, 2684.650995370609, 2686.6507354043947, 2689.341111427703, 2550.3241640965066, 2552.1067626330823, 2563.846169229843, 2564.6794150731157, 2566.3199252198183, 2559.641650168978, 2584.3151564835334, 2595.5976823592187, 2602.349412646838, 2609.0, 14615.926920365437, 14779.33492992807, 14846.406143754237, 14855.925720371437, 15000.024998375071, 7502.249775022508, 7741.148473667892, 7741.922580774177, 7759.301662850314, 7793.1817159198035, 5032.144535428986, 5178.637495375326, 5230.738463076831, 5279.44565820591, 5289.444608316148, 1943.3004118517322, 1965.5479239774788, 1991.3926252492984, 1995.7405537280206, 2009.1159889648509, 23305.533889322287, 23863.284101476984, 24143.0, 24143.75856241433, 26064.218073457636, 6756.0, 6919.515633905578, 6977.162740471176, 7053.92946070543, 7079.256678048834, 20295.594088118156, 20420.38738837837, 20449.386518404473, 20468.69296960547, 20496.0, 7691.346235570039, 7702.9229707703325, 7797.41519386037, 7946.0, 8099.352051835839, 6980.197277313073, 7086.539374940612, 7094.538854974513, 7104.11198600175, 7353.0, 5587.69267690281, 5609.382967873531, 5675.829725108253, 5683.857903552444, 5771.567132465082, 1733.7052701040852, 1740.4604572582539, 1742.7103943082027, 1749.6500699860078, 1767.0457952705651, 881.7266647339345, 923.7782932096383, 929.0709290709291, 932.9580168892417, 933.4912472702393, 7651.234876512415, 7757.9224207758325, 7770.689172433039, 7855.489393189425, 7875.803104922423, 720.7333286683855, 726.201178703422, 729.748236858283, 733.4279262175405, 743.4312750745684, 4706.976465117721, 4780.617550595944, 4807.18277892759, 4839.7096174228955, 4839.733814640229, 20734.274300399415, 21179.152833886474, 21359.14563417477, 21592.568148637252, 21597.136114555557, 20110.49723756918, 20674.276400325918, 21198.576028479652, 21295.148194072062, 21325.146994120372, 10590.046895779264, 10705.732356690989, 10718.464076796128, 10722.624708135178, 10801.0, 8246.0, 8308.210719981593, 8348.707795227381, 8426.0, 8429.915700843158, 18166.636667266317, 18169.727454088465, 18254.36109736153, 18344.266229350676, 19227.807721922603, 18671.71992420142, 19096.331628392938, 19121.426357209308, 19763.80236197677, 19938.501537461678, 11521.884781152416, 11658.475368608264, 11683.824742628698, 11706.70733231676, 11721.062315014608, 13668.248246346546, 13724.176549407068, 13732.86267137316, 13746.312684365941, 13868.098573592684, 11130.554777808797, 11442.427878606202, 11533.480993355151, 11622.70943226426, 11770.0, 11036.834447483134, 11108.222424430212, 11204.775904482029, 11298.09615230796, 11356.205065645325, 9591.616335346507, 9600.90399096028, 9752.512374381395, 9783.0706082922, 9859.605615775576, 7013.509054366349, 7042.225355211025, 7117.67970441321, 7172.175199852084, 7273.599952002691, 7050.189228238613, 7221.602811845399, 7287.927120728937, 7308.8903666443985, 7325.450591205575], "value": 6610.697144684905, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.9957936171034, 504.4185886131443, 518.3727689495711, 520.3313741841735, 524.404800551374, 6041.214642096526, 6098.207233059701, 6141.385861413856, 6142.140100385949, 6208.503319734422, 5993.700314984255, 6082.391760823915, 6111.572189946705, 6147.354527774586, 6179.845503862406, 5128.897422051576, 5132.743362831853, 5149.356330458692, 5152.716600586966, 5174.301469301652, 5079.517445842639, 5082.79668813247, 5092.414372347191, 5125.231215317714, 5163.251328557357, 4668.276417155329, 4824.758762061901, 4860.829870954517, 4951.826686065989, 5042.218456139304, 4781.880452988668, 4884.853454396374, 4888.560029597354, 4895.926561101586, 4910.8772280693265, 4350.564943505656, 4355.869323920288, 4390.934135987963, 4406.625436837872, 4424.203643344197, 5741.770329186869, 5875.059990401515, 5912.793052243151, 5952.851178720523, 5965.642061476326, 5441.374241962186, 5513.9448605513835, 5549.112142057292, 5577.231467095656, 5586.608937374385, 6324.209473815773, 6326.746930122789, 6362.809115726535, 6365.745370185187, 6461.160049193575, 4479.3281007848755, 4662.207424737769, 4685.390899183119, 4738.6209103272, 4795.184818580815, 6489.253735820346, 6499.935000649933, 6562.475001999828, 6567.4746020318735, 6739.460843132538, 4855.5387238212315, 4859.392575928009, 4864.683795553277, 4877.414710234794, 4889.45982015664, 4945.862451636144, 4974.801007959641, 4986.875328116754, 4989.126902791997, 5000.4999500050435, 5488.835334939959, 5504.889902201933, 5556.305461817273, 5569.721513924287, 5583.330000399978, 6372.681365931778, 6451.516136289703, 6464.967675161594, 6506.837329066717, 6508.349165083454, 1707.9146042697816, 1726.4389073551147, 1728.5073753980055, 1732.6361464092429, 1744.790625124993, 1144.8797876223043, 1182.532899504708, 1186.4542310537076, 1217.514632148777, 1220.6216073017392, 5567.218490083134, 5643.717814109278, 5649.180868774005, 5649.322081350264, 5682.914756278659, 6498.935010649928, 6534.901976470357, 6572.14562106923, 6606.174228221473, 6649.534532582769, 5584.190292407578, 5602.0, 5611.466910643482, 5639.403885833033, 5649.745761440663, 3266.738660907122, 3418.6239513653477, 3430.776999495025, 3444.156181735491, 3500.5274287971306, 6319.684015799192, 6400.455961243347, 6416.294207637156, 6432.292447830735, 6483.546151769331, 6507.219133703986, 6528.2166140062545, 6742.460603151736, 6752.210664173899, 6773.221079575912, 6169.809717550294, 6357.872842543124, 6374.0, 6386.584871983307, 6443.774467893789, 6447.774327898501, 6468.2561505427475, 6585.967070164618, 6617.669116544057, 6627.3372662733345, 6424.839379015562, 6431.710573024318, 6472.773452929125, 6551.705173267119, 6577.079208910852, 6336.96831515858, 6364.777232797015, 6382.0, 6388.233411990591, 6524.771632992823, 4893.632977526629, 4895.755212239445, 4910.140725373117, 4926.901461970812, 4952.752362381939, 6350.428076628973, 6528.37980391862, 6528.706208220547, 6609.933900661124, 6735.932640673531, 6132.969335153296, 6317.399847014526, 6343.52423568225, 6346.90479642815, 6408.0, 6284.371562843679, 6287.182666253358, 6306.621602703853, 6328.8165582873835, 6374.617522948638, 5942.1978032965, 6125.693715314305, 6221.3156552780065, 6236.719347629277, 6251.718672659832, 6379.2344918608205, 6411.070394792822, 6424.325445828307, 6433.485321174201, 6440.8389790255615, 6124.816255512342, 6146.248319228998, 6195.721192546436, 6213.65824879636, 6251.249850018027], "value": 4945.5382789085825, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [877.4340550345028, 923.709031655029, 924.8150369926012, 929.7164364868712, 938.3290946972916, 19167.520811979706, 19181.52046198842, 19911.30310439135, 19954.501137471554, 20005.099770510325, 18935.52661183471, 19158.71261931072, 19348.613027739437, 19356.22575096998, 19387.32144374947, 946.342292106986, 947.5688561704408, 949.9572519236616, 955.2358113509199, 956.6938579654513, 927.5455027036737, 953.7853982853837, 970.6554173268494, 972.6595691507987, 979.4270351844144, 1021.1575450253533, 1025.9384436933772, 1031.6079889641962, 1032.6695457453618, 1034.7309699478114, 1018.6994836523206, 1022.1822541966471, 1023.0281232828843, 1023.7798873242251, 1026.3687831983352, 703.525120543631, 714.5462631229155, 723.8805597076476, 731.4696844787486, 760.3347071312602, 20494.897525512428, 20723.171073157057, 20739.688904666444, 20741.27405540814, 20752.273670421462, 21559.24542641, 21612.783872161235, 22693.205737799097, 22942.311730648107, 22955.77044229553, 16297.185140743033, 16709.331626734915, 16827.074510901897, 16961.406350777663, 17037.148142592945, 3791.75353602015, 3826.693864490834, 3835.060410199491, 3835.520559930009, 3836.136869204435, 138910.0, 141505.29247353697, 142679.0, 144427.0, 145036.0, 16070.43753468623, 16410.343586256655, 16414.425495107615, 16494.17529123539, 16585.751213731804, 30609.540856887164, 30815.68882333834, 31985.0, 32265.51601725976, 32274.031779046665, 8522.488650680854, 8577.485350878967, 8601.31189504838, 8612.655493780305, 8638.352123590756, 4325.264705000158, 4439.800208990603, 4502.482214545304, 4505.909881802411, 4542.931856022163, 5311.0, 5421.701806400607, 5431.701256430934, 5464.508194262536, 5477.123660214346, 984.0749695286407, 1013.7465633591602, 1030.2324768047852, 1030.299396410433, 1032.5869652139204, 667.7629441548278, 680.3332733920687, 689.1557841643946, 704.6547191876047, 705.1925545250659, 10206.285560010729, 10295.330803497749, 10366.429846358522, 10650.254482186327, 10877.456127193609, 692.2904023376079, 697.6581475077277, 711.1466240511407, 718.3319512853096, 719.7768691705588, 5203.245529398216, 5264.447233040552, 5344.35867695879, 5389.326334208224, 5485.122380419114, 123246.0, 124326.37836810759, 124718.0, 125520.0, 127046.0, 99700.0, 111518.0, 112453.6263912084, 112790.0, 114158.4292078551, 93506.53246733722, 98618.50690746644, 100312.49843750881, 100576.0, 100981.49509252554, 10736.141108711128, 10977.3962432067, 10978.176636752436, 11062.557497700001, 11362.0, 42675.57324426716, 42869.0, 43865.34201986909, 44196.0, 44211.55788442074, 55231.0, 55287.44712552823, 56145.43854561402, 56771.71614142068, 57714.134287986555, 16009.199540022719, 16141.273642685872, 16188.838111619205, 16252.918735406247, 16540.83459165393, 17813.46559603214, 17829.821701782817, 17884.642307153525, 17939.641207176046, 18017.729734053737, 16262.756058658893, 16351.59121021984, 16470.917645411697, 16559.66880662356, 16771.832281677027, 16812.57968550796, 16889.577760556083, 16979.23593438323, 16990.83009169942, 17011.48965531036, 15182.848171518144, 15245.771313430529, 15474.922625386802, 15509.457168999032, 15616.609584760472, 10767.3539587625, 10871.184661150288, 10892.673219803419, 11323.377214253294, 11326.263792853439, 12124.211926224767, 12383.380830958597, 12441.315727635067, 12486.750264994831, 12632.74734505323], "value": 8963.330873050036, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1478.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 116.0, 112.0, 115.0, 111.0, 115.0, 108.0, 109.0, 113.0, 111.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 117.0, 119.0, 127.0, 121.0, 122.0, 123.0, 143.0, 126.0, 117.0], "value": 123.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 175.0, 163.0, 164.0, 168.0, 174.0, 171.0, 170.0, 172.0, 162.0], "value": 169.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 75.0, 78.0, 79.0, 74.0, 74.0, 80.0, 79.0, 83.0], "value": 77.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 85.0, 79.0, 81.0, 84.0, 84.0, 79.0, 82.0, 80.0, 86.0], "value": 82.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 116.0, 121.0, 121.0, 117.0, 124.0, 114.0, 115.0, 114.0, 115.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 154.0, 151.0, 150.0, 150.0, 150.0, 149.0, 149.0, 151.0, 155.0], "value": 150.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 104.0, 94.0, 95.0, 95.0, 95.0, 95.0, 101.0, 94.0, 96.0], "value": 96.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 52.0, 53.0, 53.0, 54.0, 56.0, 54.0, 53.0, 53.0, 52.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 47.0, 38.0, 46.0, 43.0, 48.0, 49.0, 39.0, 46.0, 43.0], "value": 43.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 90.0, 83.0, 86.0, 81.0, 77.0, 88.0, 83.0, 80.0, 80.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 131.0, 133.0, 120.0, 119.0, 131.0, 120.0, 123.0, 118.0, 134.0], "value": 126.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 179.0, 173.0, 171.0, 170.0, 168.0, 173.0, 175.0, 173.0, 173.0], "value": 173.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 64.0, 63.0, 68.0, 73.0, 76.0, 69.0, 64.0, 64.0], "value": 68.2, "unit": "score"}]}]} 16:09:22 INFO - Return code: 0 16:09:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:09:22 INFO - # TBPL SUCCESS # 16:09:22 INFO - Running post-action listener: _package_coverage_data 16:09:22 INFO - Running post-action listener: _resource_record_post_action 16:09:22 INFO - [mozharness: 2017-09-07 23:09:22.445445Z] Finished run-tests step (success) 16:09:22 INFO - Running post-run listener: _resource_record_post_run 16:09:22 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 8192; Write bytes: 396201984; Read time: 52; Write time: 342480 16:09:22 INFO - TinderboxPrint: CPU usage
13.3% 16:09:22 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 52 16:09:22 INFO - TinderboxPrint: I/O write bytes / time
396,201,984 / 342,480 16:09:22 INFO - TinderboxPrint: CPU idle
8,243.9 (86.7%) 16:09:22 INFO - TinderboxPrint: CPU user
1,199.8 (12.6%) 16:09:22 INFO - TinderboxPrint: Swap in / out
0 / 0 16:09:22 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 115064832; Read time: 0; Write time: 149824 16:09:22 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:09:22 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 8192; Write bytes: 281137152; Read time: 52; Write time: 192656 16:09:23 INFO - Running post-run listener: _upload_blobber_files 16:09:23 INFO - Blob upload gear active. 16:09:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:09:23 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 16:09:23 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'] 16:09:23 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 16:09:23 INFO - (blobuploader) - INFO - Open directory for files ... 16:09:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:09:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:09:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:09:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:09:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:09:24 INFO - (blobuploader) - INFO - Done attempting. 16:09:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:09:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:09:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:09:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:09:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:09:25 INFO - (blobuploader) - INFO - Done attempting. 16:09:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:09:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:09:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:09:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:09:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:09:27 INFO - (blobuploader) - INFO - Done attempting. 16:09:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:09:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:09:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:09:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:09:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:09:30 INFO - (blobuploader) - INFO - Done attempting. 16:09:30 INFO - (blobuploader) - INFO - Iteration through files over. 16:09:30 INFO - Return code: 0 16:09:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:09:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:09:30 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8406fd85e2557b20f57180a4a3796686b1ea6e37d0df2e7360dc91933340b06d510ce214ce3e4a2c678f77f6cc0cd181ce1bc818bdc57fb5cb595826787393de", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e66972e8ac26bccfeb1227c8993e2d4d7a2f12774f572674b4d3a54593b79217a64c819fc7589ea70312d0365231e2887e2d4561d85109afdb54deb89026463a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e36ab85834dd3602ffecf948850e43260885f9f7faeb981a1822c61c22f01240c8c2161ca48cb00872e349fa4d8c9aa8418d6ebc62341b9d0ab97c060846c036", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f3cbf82f25dd402d4dfcf7198d86341b52bf3fc35a68eb4bd974da0d7a50296fb7ed0927c79126852f4c889b8b0e641d392e9b485678b37f0895cbaedd2c3c8"} 16:09:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:09:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:09:30 INFO - Contents: 16:09:30 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8406fd85e2557b20f57180a4a3796686b1ea6e37d0df2e7360dc91933340b06d510ce214ce3e4a2c678f77f6cc0cd181ce1bc818bdc57fb5cb595826787393de", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e66972e8ac26bccfeb1227c8993e2d4d7a2f12774f572674b4d3a54593b79217a64c819fc7589ea70312d0365231e2887e2d4561d85109afdb54deb89026463a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e36ab85834dd3602ffecf948850e43260885f9f7faeb981a1822c61c22f01240c8c2161ca48cb00872e349fa4d8c9aa8418d6ebc62341b9d0ab97c060846c036", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f3cbf82f25dd402d4dfcf7198d86341b52bf3fc35a68eb4bd974da0d7a50296fb7ed0927c79126852f4c889b8b0e641d392e9b485678b37f0895cbaedd2c3c8"} 16:09:30 INFO - Running post-run listener: copy_logs_to_upload_dir 16:09:30 INFO - Copying logs to upload dir... 16:09:30 INFO - mkdir: /builds/slave/test/build/upload/logs 16:09:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.176623 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-09-07 16:09:30.695493) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-07 16:09:30.699522) ========= 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=db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976 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/8406fd85e2557b20f57180a4a3796686b1ea6e37d0df2e7360dc91933340b06d510ce214ce3e4a2c678f77f6cc0cd181ce1bc818bdc57fb5cb595826787393de", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e66972e8ac26bccfeb1227c8993e2d4d7a2f12774f572674b4d3a54593b79217a64c819fc7589ea70312d0365231e2887e2d4561d85109afdb54deb89026463a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e36ab85834dd3602ffecf948850e43260885f9f7faeb981a1822c61c22f01240c8c2161ca48cb00872e349fa4d8c9aa8418d6ebc62341b9d0ab97c060846c036", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f3cbf82f25dd402d4dfcf7198d86341b52bf3fc35a68eb4bd974da0d7a50296fb7ed0927c79126852f4c889b8b0e641d392e9b485678b37f0895cbaedd2c3c8"} build_url:https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011479 build_url: 'https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8406fd85e2557b20f57180a4a3796686b1ea6e37d0df2e7360dc91933340b06d510ce214ce3e4a2c678f77f6cc0cd181ce1bc818bdc57fb5cb595826787393de", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e66972e8ac26bccfeb1227c8993e2d4d7a2f12774f572674b4d3a54593b79217a64c819fc7589ea70312d0365231e2887e2d4561d85109afdb54deb89026463a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e36ab85834dd3602ffecf948850e43260885f9f7faeb981a1822c61c22f01240c8c2161ca48cb00872e349fa4d8c9aa8418d6ebc62341b9d0ab97c060846c036", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f3cbf82f25dd402d4dfcf7198d86341b52bf3fc35a68eb4bd974da0d7a50296fb7ed0927c79126852f4c889b8b0e641d392e9b485678b37f0895cbaedd2c3c8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-07 16:09:30.728165) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 16:09:30.728498) ========= 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=db7e7c15f3ca076d53731445000001ba-1504824515.940116-1739519976 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004493 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 16:09:30.814692) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-07 16:09:30.815068) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-07 16:09:30.815387) ========= ========= Total master_lag: 0.21 =========