builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-079 starttime: 1504204016.28 results: success (0) revision: 4984da22242841a5d84c4e5fd866e93a450d9723 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-31 11:26:56.284518) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-31 11:26:56.285016) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-31 11:26:56.285289) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-31 11:26:56.303086) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 11:26:56.303684) ========= 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=1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-31 11:26:56-- 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% 53.6M=0s 2017-08-31 11:26:56 (53.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.149712 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 11:26:56.471956) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 11:26:56.472602) ========= 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=1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020723 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 11:26:56.524812) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-31 11:26:56.525332) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4984da22242841a5d84c4e5fd866e93a450d9723 --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 4984da22242841a5d84c4e5fd866e93a450d9723 --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=1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-31 11:26:56,577 truncating revision to first 12 chars 2017-08-31 11:26:56,577 Setting DEBUG logging. 2017-08-31 11:26:56,577 attempt 1/10 2017-08-31 11:26:56,577 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4984da222428?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-31 11:26:57,992 unpacking tar archive at: mozilla-central-4984da222428/testing/mozharness/ program finished with exit code 0 elapsedTime=1.972160 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-31 11:26:58.514797) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-31 11:26:58.515336) ========= script_repo_revision: 4984da22242841a5d84c4e5fd866e93a450d9723 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-31 11:26:58.515925) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-31 11:26:58.516294) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-31 11:26:58.529682) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-08-31 11:26:58.530244) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:26:58 INFO - MultiFileLogger online at 20170831 11:26:58 in /builds/slave/test 11:26:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 11:26:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:26:58 INFO - {'append_to_log': False, 11:26:58 INFO - 'base_work_dir': '/builds/slave/test', 11:26:58 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 11:26:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:26:58 INFO - 'branch': 'Firefox-Non-PGO', 11:26:58 INFO - 'buildbot_json_path': 'buildprops.json', 11:26:58 INFO - 'code_coverage': False, 11:26:58 INFO - 'config_files': ('talos/linux_config.py',), 11:26:58 INFO - 'default_actions': ('clobber', 11:26:58 INFO - 'read-buildbot-config', 11:26:58 INFO - 'download-and-extract', 11:26:58 INFO - 'populate-webroot', 11:26:58 INFO - 'create-virtualenv', 11:26:58 INFO - 'install', 11:26:58 INFO - 'setup-mitmproxy', 11:26:58 INFO - 'run-tests'), 11:26:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:26:58 INFO - 'disable_ccov_upload': False, 11:26:58 INFO - 'disable_stylo': False, 11:26:58 INFO - 'download_minidump_stackwalk': True, 11:26:58 INFO - 'download_symbols': 'ondemand', 11:26:58 INFO - 'enable_stylo': False, 11:26:58 INFO - 'enable_webrender': False, 11:26:58 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:26:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:26:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:26:58 INFO - 'gecko_profile': False, 11:26:58 INFO - 'gecko_profile_interval': 0, 11:26:58 INFO - 'installer_path': 'installer.exe', 11:26:58 INFO - 'log_level': 'info', 11:26:58 INFO - 'log_name': 'talos', 11:26:58 INFO - 'log_to_console': True, 11:26:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:26:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:26:58 INFO - 'opt_config_files': (), 11:26:58 INFO - 'pip_index': False, 11:26:58 INFO - 'suite': 'dromaeojs-e10s', 11:26:58 INFO - 'system_bits': '32', 11:26:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:26:58 INFO - 'title': 'talos-linux64-ix-079', 11:26:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:26:58 INFO - 'use_talos_json': True, 11:26:58 INFO - 'verify': 'False', 11:26:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:26:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:26:58 INFO - 'work_dir': 'build'} 11:26:58 INFO - [mozharness: 2017-08-31 18:26:58.726841Z] Running clobber step. 11:26:58 INFO - Running pre-action listener: _resource_record_pre_action 11:26:58 INFO - Running main action method: clobber 11:26:58 INFO - rmtree: /builds/slave/test/build 11:26:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:26:59 INFO - Running post-action listener: _resource_record_post_action 11:26:59 INFO - [mozharness: 2017-08-31 18:26:59.745650Z] Finished clobber step (success) 11:26:59 INFO - [mozharness: 2017-08-31 18:26:59.745747Z] Running read-buildbot-config step. 11:26:59 INFO - Running pre-action listener: _resource_record_pre_action 11:26:59 INFO - Running main action method: read_buildbot_config 11:26:59 INFO - Using buildbot properties: 11:26:59 INFO - { 11:26:59 INFO - "project": "", 11:26:59 INFO - "product": "firefox", 11:26:59 INFO - "who": "ffxbld@noreply.mozilla.org", 11:26:59 INFO - "installer_path": "public/build/target.tar.bz2", 11:26:59 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 11:26:59 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:26:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 11:26:59 INFO - "stage_platform": "linux64", 11:26:59 INFO - "basedir": "/builds/slave/test", 11:26:59 INFO - "buildnumber": 1106, 11:26:59 INFO - "platform": "ubuntu64_hw", 11:26:59 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:26:59 INFO - "slavebuilddir": "test", 11:26:59 INFO - "taskId": "GC20BSr3TECPE3e_G-keQA", 11:26:59 INFO - "branch": "mozilla-central", 11:26:59 INFO - "script_repo_revision": "production", 11:26:59 INFO - "revision": "4984da22242841a5d84c4e5fd866e93a450d9723", 11:26:59 INFO - "slavename": "talos-linux64-ix-079", 11:26:59 INFO - "repo_path": "mozilla-central" 11:26:59 INFO - } 11:26:59 INFO - Finding installer, test and symbols from parent task. 11:26:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GC20BSr3TECPE3e_G-keQA'}, attempt #1 11:27:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GC20BSr3TECPE3e_G-keQA'}, attempt #1 11:27:00 INFO - Task dependencies: JlZ4lx7RTwaSgq5fLFMGvQ 11:27:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ'}, attempt #1 11:27:01 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.tar.bz2 11:27:01 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.test_packages.json 11:27:01 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.crashreporter-symbols.zip 11:27:01 INFO - Running post-action listener: _resource_record_post_action 11:27:01 INFO - [mozharness: 2017-08-31 18:27:01.209931Z] Finished read-buildbot-config step (success) 11:27:01 INFO - [mozharness: 2017-08-31 18:27:01.210148Z] Running download-and-extract step. 11:27:01 INFO - Running pre-action listener: _resource_record_pre_action 11:27:01 INFO - Running main action method: download_and_extract 11:27:01 INFO - mkdir: /builds/slave/test/build/tests 11:27:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:01 INFO - trying https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.test_packages.json 11:27:01 INFO - Downloading https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:27:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:27:01 INFO - Downloaded 989 bytes. 11:27:01 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:27:01 INFO - Using the following test package requirements: 11:27:01 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:27:01 INFO - u'common': [u'target.common.tests.zip'], 11:27:01 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:27:01 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:27:01 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:27:01 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:27:01 INFO - u'mozbase': [u'target.common.tests.zip'], 11:27:01 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:27:01 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:27:01 INFO - u'web-platform': [u'target.common.tests.zip', 11:27:01 INFO - u'target.web-platform.tests.tar.gz'], 11:27:01 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:27:01 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:27:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.common.tests.zip 11:27:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:27:01 INFO - Fetch https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.common.tests.zip into memory 11:27:02 INFO - Content-Length response header: 54932997 11:27:02 INFO - Bytes received: 54932997 11:27:13 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:27:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.common.tests.zip 11:27:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:27:13 INFO - Fetch https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.common.tests.zip into memory 11:27:14 INFO - Content-Length response header: 54932997 11:27:14 INFO - Bytes received: 54932997 11:27:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.talos.tests.zip 11:27:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:27:26 INFO - Fetch https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.talos.tests.zip into memory 11:27:27 INFO - Content-Length response header: 14496650 11:27:27 INFO - Bytes received: 14496650 11:27:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:27 INFO - trying https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.tar.bz2 11:27:27 INFO - Downloading https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:27:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:27:29 INFO - Downloaded 62586538 bytes. 11:27:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.tar.bz2 11:27:29 INFO - mkdir: /builds/slave/test/properties 11:27:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:27:29 INFO - Writing to file /builds/slave/test/properties/build_url 11:27:29 INFO - Contents: 11:27:29 INFO - build_url:https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.tar.bz2 11:27:29 INFO - Running post-action listener: _resource_record_post_action 11:27:29 INFO - Running post-action listener: find_tests_for_verification 11:27:29 INFO - Running post-action listener: set_extra_try_arguments 11:27:29 INFO - [mozharness: 2017-08-31 18:27:29.145484Z] Finished download-and-extract step (success) 11:27:29 INFO - [mozharness: 2017-08-31 18:27:29.145581Z] Running populate-webroot step. 11:27:29 INFO - Running pre-action listener: _resource_record_pre_action 11:27:29 INFO - Running main action method: populate_webroot 11:27:29 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:27:29 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:27:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:27:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:27:29 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:27:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:27:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:27:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:27:29 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:27:29 INFO - 'talos_options': ['--enable-stylo'], 11:27:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:27:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:27:29 INFO - 'tests': ['damp', 'tps']}, 11:27:29 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:27:29 INFO - 'talos_options': ['--enable-stylo'], 11:27:29 INFO - 'tests': ['damp', 'tps']}, 11:27:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:27:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:27:29 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:27:29 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:27:29 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:27:29 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:27:29 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:27:29 INFO - 'talos_options': ['--enable-stylo'], 11:27:29 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:27:29 INFO - 'other-e10s': {'tests': ['a11yr', 11:27:29 INFO - 'ts_paint', 11:27:29 INFO - 'tpaint', 11:27:29 INFO - 'sessionrestore', 11:27:29 INFO - 'sessionrestore_many_windows', 11:27:29 INFO - 'sessionrestore_no_auto_restore', 11:27:29 INFO - 'tabpaint']}, 11:27:29 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:27:29 INFO - 'tests': ['a11yr', 11:27:29 INFO - 'ts_paint', 11:27:29 INFO - 'tpaint', 11:27:29 INFO - 'sessionrestore', 11:27:29 INFO - 'sessionrestore_many_windows', 11:27:29 INFO - 'sessionrestore_no_auto_restore', 11:27:29 INFO - 'tabpaint']}, 11:27:29 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:27:29 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:27:29 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:27:29 INFO - 'tests': ['bloom_basic_singleton']}, 11:27:29 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:27:29 INFO - 'tests': ['bloom_basic']}, 11:27:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:27:29 INFO - 'tsvgr_opacity', 11:27:29 INFO - 'tart', 11:27:29 INFO - 'tscrollx', 11:27:29 INFO - 'tsvg_static']}, 11:27:29 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:27:29 INFO - 'tests': ['tsvgx', 11:27:29 INFO - 'tsvgr_opacity', 11:27:29 INFO - 'tart', 11:27:29 INFO - 'tscrollx', 11:27:29 INFO - 'tsvg_static']}, 11:27:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:27:29 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:27:29 INFO - 'talos_options': ['--enable-stylo'], 11:27:29 INFO - 'tests': ['tp5o']}, 11:27:29 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:27:29 INFO - 'talos_options': ['--mitmproxy', 11:27:29 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:27:29 INFO - '--firstNonBlankPaint'], 11:27:29 INFO - 'tests': ['tp6_google', 11:27:29 INFO - 'tp6_youtube', 11:27:29 INFO - 'tp6_amazon', 11:27:29 INFO - 'tp6_facebook']}, 11:27:29 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:27:29 INFO - 'talos_options': ['--enable-stylo', 11:27:29 INFO - '--mitmproxy', 11:27:29 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:27:29 INFO - '--firstNonBlankPaint'], 11:27:29 INFO - 'tests': ['tp6_google', 11:27:29 INFO - 'tp6_youtube', 11:27:29 INFO - 'tp6_amazon', 11:27:29 INFO - 'tp6_facebook']}, 11:27:29 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:27:29 INFO - 'talos_options': ['--enable-stylo', 11:27:29 INFO - '--stylo-threads=1', 11:27:29 INFO - '--mitmproxy', 11:27:29 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:27:29 INFO - '--firstNonBlankPaint'], 11:27:29 INFO - 'tests': ['tp6_google', 11:27:29 INFO - 'tp6_youtube', 11:27:29 INFO - 'tp6_amazon', 11:27:29 INFO - 'tp6_facebook']}, 11:27:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:27:29 INFO - 'talos_options': ['--xperf_path', 11:27:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:27:29 INFO - 'tests': ['tp5n']}, 11:27:29 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:27:29 INFO - 'talos_options': ['--enable-stylo', 11:27:29 INFO - '--xperf_path', 11:27:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:27:29 INFO - 'tests': ['tp5n']}}} 11:27:29 INFO - Running post-action listener: _resource_record_post_action 11:27:29 INFO - [mozharness: 2017-08-31 18:27:29.159840Z] Finished populate-webroot step (success) 11:27:29 INFO - [mozharness: 2017-08-31 18:27:29.159928Z] Running create-virtualenv step. 11:27:29 INFO - Running pre-action listener: _resource_record_pre_action 11:27:29 INFO - Running main action method: create_virtualenv 11:27:29 INFO - Creating virtualenv /builds/slave/test/build/venv 11:27:29 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:27:29 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:27:29 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:27:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:27:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:27:29 INFO - Installing distribute.............................................................................................................................................................................................done. 11:27:30 INFO - Installing pip...............done. 11:27:30 INFO - Return code: 0 11:27:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:27:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:27:30 INFO - Reading from file tmpfile_stdout 11:27:30 INFO - Output received: 11:27:30 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:27:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:27:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b6a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d32a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed6580>, '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 0x1ed7480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed7910>, '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': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', '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 11:27:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:27:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:27:30 INFO - Using env: {'DISPLAY': ':0', 11:27:30 INFO - 'HOME': '/home/cltbld', 11:27:30 INFO - 'LANG': 'en_US.UTF-8', 11:27:30 INFO - 'LANGUAGE': 'en_US:en', 11:27:30 INFO - 'LOGNAME': 'cltbld', 11:27:30 INFO - 'MAIL': '/var/mail/cltbld', 11:27:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:30 INFO - 'MOZ_NO_REMOTE': '1', 11:27:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:30 INFO - 'NO_EM_RESTART': '1', 11:27:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:30 INFO - 'PWD': '/builds/slave/test', 11:27:30 INFO - 'SHELL': '/bin/bash', 11:27:30 INFO - 'SHLVL': '1', 11:27:30 INFO - 'TERM': 'linux', 11:27:30 INFO - 'TMOUT': '86400', 11:27:30 INFO - 'USER': 'cltbld', 11:27:30 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', 11:27:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:30 INFO - '_': '/tools/buildbot/bin/python'} 11:27:31 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:27:31 INFO - Downloading/unpacking pip>=1.5 11:27:31 INFO - Running setup.py egg_info for package pip 11:27:31 INFO - warning: no files found matching 'pip/cacert.pem' 11:27:31 INFO - warning: no files found matching '*.html' under directory 'docs' 11:27:31 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:27:31 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:27:31 INFO - Installing collected packages: pip 11:27:31 INFO - Found existing installation: pip 1.1 11:27:31 INFO - Uninstalling pip: 11:27:31 INFO - Successfully uninstalled pip 11:27:31 INFO - Running setup.py install for pip 11:27:31 INFO - warning: no files found matching 'pip/cacert.pem' 11:27:31 INFO - warning: no files found matching '*.html' under directory 'docs' 11:27:31 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:27:31 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:27:31 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:27:31 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:27:31 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:27:31 INFO - Successfully installed pip 11:27:31 INFO - Cleaning up... 11:27:31 INFO - Return code: 0 11:27:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:27:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1b6a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d32a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed6580>, '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 0x1ed7480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed7910>, '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': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', '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 11:27:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:27:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:27:31 INFO - Using env: {'DISPLAY': ':0', 11:27:31 INFO - 'HOME': '/home/cltbld', 11:27:31 INFO - 'LANG': 'en_US.UTF-8', 11:27:31 INFO - 'LANGUAGE': 'en_US:en', 11:27:31 INFO - 'LOGNAME': 'cltbld', 11:27:31 INFO - 'MAIL': '/var/mail/cltbld', 11:27:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:31 INFO - 'MOZ_NO_REMOTE': '1', 11:27:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:31 INFO - 'NO_EM_RESTART': '1', 11:27:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:31 INFO - 'PWD': '/builds/slave/test', 11:27:31 INFO - 'SHELL': '/bin/bash', 11:27:31 INFO - 'SHLVL': '1', 11:27:31 INFO - 'TERM': 'linux', 11:27:31 INFO - 'TMOUT': '86400', 11:27:31 INFO - 'USER': 'cltbld', 11:27:31 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', 11:27:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:31 INFO - '_': '/tools/buildbot/bin/python'} 11:27:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:31 INFO - Downloading/unpacking psutil>=3.1.1 11:27:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:27:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:27:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:27:32 INFO - Installing collected packages: psutil 11:27:32 INFO - Running setup.py install for psutil 11:27:32 INFO - building 'psutil._psutil_linux' extension 11:27:32 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 11:27:33 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 11:27:33 INFO - building 'psutil._psutil_posix' extension 11:27:33 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 11:27:33 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 11:27:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:27:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:27:33 INFO - Successfully installed psutil 11:27:33 INFO - Cleaning up... 11:27:33 INFO - Return code: 0 11:27:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:27:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b6a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d32a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed6580>, '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 0x1ed7480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed7910>, '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': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', '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 11:27:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:27:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:27:33 INFO - Using env: {'DISPLAY': ':0', 11:27:33 INFO - 'HOME': '/home/cltbld', 11:27:33 INFO - 'LANG': 'en_US.UTF-8', 11:27:33 INFO - 'LANGUAGE': 'en_US:en', 11:27:33 INFO - 'LOGNAME': 'cltbld', 11:27:33 INFO - 'MAIL': '/var/mail/cltbld', 11:27:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:33 INFO - 'MOZ_NO_REMOTE': '1', 11:27:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:33 INFO - 'NO_EM_RESTART': '1', 11:27:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:33 INFO - 'PWD': '/builds/slave/test', 11:27:33 INFO - 'SHELL': '/bin/bash', 11:27:33 INFO - 'SHLVL': '1', 11:27:33 INFO - 'TERM': 'linux', 11:27:33 INFO - 'TMOUT': '86400', 11:27:33 INFO - 'USER': 'cltbld', 11:27:33 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', 11:27:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:33 INFO - '_': '/tools/buildbot/bin/python'} 11:27:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:27:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:27:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:27:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:27:34 INFO - Installing collected packages: mozsystemmonitor 11:27:34 INFO - Running setup.py install for mozsystemmonitor 11:27:34 INFO - Successfully installed mozsystemmonitor 11:27:34 INFO - Cleaning up... 11:27:34 INFO - Return code: 0 11:27:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:27:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b6a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d32a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed6580>, '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 0x1ed7480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed7910>, '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': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', '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 11:27:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:27:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:27:34 INFO - Using env: {'DISPLAY': ':0', 11:27:34 INFO - 'HOME': '/home/cltbld', 11:27:34 INFO - 'LANG': 'en_US.UTF-8', 11:27:34 INFO - 'LANGUAGE': 'en_US:en', 11:27:34 INFO - 'LOGNAME': 'cltbld', 11:27:34 INFO - 'MAIL': '/var/mail/cltbld', 11:27:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:34 INFO - 'MOZ_NO_REMOTE': '1', 11:27:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:34 INFO - 'NO_EM_RESTART': '1', 11:27:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:34 INFO - 'PWD': '/builds/slave/test', 11:27:34 INFO - 'SHELL': '/bin/bash', 11:27:34 INFO - 'SHLVL': '1', 11:27:34 INFO - 'TERM': 'linux', 11:27:34 INFO - 'TMOUT': '86400', 11:27:34 INFO - 'USER': 'cltbld', 11:27:34 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', 11:27:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:34 INFO - '_': '/tools/buildbot/bin/python'} 11:27:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:35 INFO - Downloading/unpacking jsonschema==2.5.1 11:27:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:27:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:27:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:36 INFO - Downloading functools32-3.2.3-2.tar.gz 11:27:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:27:36 INFO - warning: no files found matching '*.txt' 11:27:36 INFO - no previously-included directories found matching 'build' 11:27:36 INFO - no previously-included directories found matching 'dist' 11:27:36 INFO - no previously-included directories found matching '.git*' 11:27:36 INFO - Installing collected packages: jsonschema, functools32 11:27:36 INFO - Running setup.py install for functools32 11:27:36 INFO - warning: no files found matching '*.txt' 11:27:36 INFO - no previously-included directories found matching 'build' 11:27:36 INFO - no previously-included directories found matching 'dist' 11:27:36 INFO - no previously-included directories found matching '.git*' 11:27:36 INFO - Successfully installed jsonschema functools32 11:27:36 INFO - Cleaning up... 11:27:36 INFO - Return code: 0 11:27:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:27:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b6a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d32a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed6580>, '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 0x1ed7480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed7910>, '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': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', '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 11:27:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:27:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:27:36 INFO - Using env: {'DISPLAY': ':0', 11:27:36 INFO - 'HOME': '/home/cltbld', 11:27:36 INFO - 'LANG': 'en_US.UTF-8', 11:27:36 INFO - 'LANGUAGE': 'en_US:en', 11:27:36 INFO - 'LOGNAME': 'cltbld', 11:27:36 INFO - 'MAIL': '/var/mail/cltbld', 11:27:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:36 INFO - 'MOZ_NO_REMOTE': '1', 11:27:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:36 INFO - 'NO_EM_RESTART': '1', 11:27:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:36 INFO - 'PWD': '/builds/slave/test', 11:27:36 INFO - 'SHELL': '/bin/bash', 11:27:36 INFO - 'SHLVL': '1', 11:27:36 INFO - 'TERM': 'linux', 11:27:36 INFO - 'TMOUT': '86400', 11:27:36 INFO - 'USER': 'cltbld', 11:27:36 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', 11:27:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:36 INFO - '_': '/tools/buildbot/bin/python'} 11:27:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:27:36 INFO - Cleaning up... 11:27:36 INFO - Return code: 0 11:27:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:27:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b6a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d32a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed6580>, '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 0x1ed7480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed7910>, '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': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', '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 11:27:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:27:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:27:36 INFO - Using env: {'DISPLAY': ':0', 11:27:36 INFO - 'HOME': '/home/cltbld', 11:27:36 INFO - 'LANG': 'en_US.UTF-8', 11:27:36 INFO - 'LANGUAGE': 'en_US:en', 11:27:36 INFO - 'LOGNAME': 'cltbld', 11:27:36 INFO - 'MAIL': '/var/mail/cltbld', 11:27:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:36 INFO - 'MOZ_NO_REMOTE': '1', 11:27:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:36 INFO - 'NO_EM_RESTART': '1', 11:27:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:36 INFO - 'PWD': '/builds/slave/test', 11:27:36 INFO - 'SHELL': '/bin/bash', 11:27:36 INFO - 'SHLVL': '1', 11:27:36 INFO - 'TERM': 'linux', 11:27:36 INFO - 'TMOUT': '86400', 11:27:36 INFO - 'USER': 'cltbld', 11:27:36 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', 11:27:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:36 INFO - '_': '/tools/buildbot/bin/python'} 11:27:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:36 INFO - Downloading/unpacking blobuploader==1.2.4 11:27:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:38 INFO - Downloading blobuploader-1.2.4.tar.gz 11:27:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:27:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:27:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:27:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:27:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:38 INFO - Downloading docopt-0.6.1.tar.gz 11:27:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:27:38 INFO - Installing collected packages: blobuploader, requests, docopt 11:27:38 INFO - Running setup.py install for blobuploader 11:27:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:27:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:27:38 INFO - Running setup.py install for requests 11:27:39 INFO - Running setup.py install for docopt 11:27:39 INFO - Successfully installed blobuploader requests docopt 11:27:39 INFO - Cleaning up... 11:27:39 INFO - Return code: 0 11:27:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:27:39 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')]} 11:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:39 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')]} 11:27:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:39 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 0x1b6a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d32a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed6580>, '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 0x1ed7480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed7910>, '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': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', '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 11:27:39 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 11:27:39 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 11:27:39 INFO - Using env: {'DISPLAY': ':0', 11:27:39 INFO - 'HOME': '/home/cltbld', 11:27:39 INFO - 'LANG': 'en_US.UTF-8', 11:27:39 INFO - 'LANGUAGE': 'en_US:en', 11:27:39 INFO - 'LOGNAME': 'cltbld', 11:27:39 INFO - 'MAIL': '/var/mail/cltbld', 11:27:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:39 INFO - 'MOZ_NO_REMOTE': '1', 11:27:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:39 INFO - 'NO_EM_RESTART': '1', 11:27:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:39 INFO - 'PWD': '/builds/slave/test', 11:27:39 INFO - 'SHELL': '/bin/bash', 11:27:39 INFO - 'SHLVL': '1', 11:27:39 INFO - 'TERM': 'linux', 11:27:39 INFO - 'TMOUT': '86400', 11:27:39 INFO - 'USER': 'cltbld', 11:27:39 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', 11:27:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:39 INFO - '_': '/tools/buildbot/bin/python'} 11:27:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:27:39 INFO - Running setup.py (path:/tmp/pip-NX8vQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:27:39 INFO - Running setup.py (path:/tmp/pip-luiymJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:27:39 INFO - Running setup.py (path:/tmp/pip-f62k4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:27:39 INFO - Running setup.py (path:/tmp/pip-aDYdby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:27:39 INFO - Running setup.py (path:/tmp/pip-exvqnv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:27:39 INFO - Running setup.py (path:/tmp/pip-FntJh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:27:39 INFO - Running setup.py (path:/tmp/pip-oA2Ro4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:27:39 INFO - Running setup.py (path:/tmp/pip-kpER3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:27:40 INFO - Running setup.py (path:/tmp/pip-ZVJlAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:27:40 INFO - Running setup.py (path:/tmp/pip-0FfrBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:27:40 INFO - Running setup.py (path:/tmp/pip-DdSPlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:27:40 INFO - Running setup.py (path:/tmp/pip-tSZt7d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:27:40 INFO - Running setup.py (path:/tmp/pip-pM090T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:27:40 INFO - Running setup.py (path:/tmp/pip-5ZDxN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:27:40 INFO - Running setup.py (path:/tmp/pip-M8hwgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:27:40 INFO - Running setup.py (path:/tmp/pip-mZFBTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:27:40 INFO - Running setup.py (path:/tmp/pip-8E1vaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:27:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:27:40 INFO - Running setup.py install for manifestparser 11:27:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:27:40 INFO - Running setup.py install for mozcrash 11:27:40 INFO - Running setup.py install for mozdebug 11:27:40 INFO - Running setup.py install for mozdevice 11:27:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:27:41 INFO - Running setup.py install for mozfile 11:27:41 INFO - Running setup.py install for mozhttpd 11:27:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:27:41 INFO - Running setup.py install for mozinfo 11:27:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:27:41 INFO - Running setup.py install for mozInstall 11:27:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:27:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:27:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:27:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:27:41 INFO - Running setup.py install for mozleak 11:27:41 INFO - Running setup.py install for mozlog 11:27:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:27:41 INFO - Running setup.py install for moznetwork 11:27:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:27:41 INFO - Running setup.py install for mozprocess 11:27:41 INFO - Running setup.py install for mozprofile 11:27:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:27:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:27:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:27:41 INFO - Running setup.py install for mozrunner 11:27:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:27:42 INFO - Running setup.py install for mozscreenshot 11:27:42 INFO - Running setup.py install for moztest 11:27:42 INFO - Running setup.py install for mozversion 11:27:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:27:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:27:42 INFO - Cleaning up... 11:27:42 INFO - Return code: 0 11:27:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:27:42 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')]} 11:27:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:42 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')]} 11:27:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b6a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d32a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed6580>, '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 0x1ed7480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed7910>, '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': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', '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 11:27:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:27:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:27:42 INFO - Using env: {'DISPLAY': ':0', 11:27:42 INFO - 'HOME': '/home/cltbld', 11:27:42 INFO - 'LANG': 'en_US.UTF-8', 11:27:42 INFO - 'LANGUAGE': 'en_US:en', 11:27:42 INFO - 'LOGNAME': 'cltbld', 11:27:42 INFO - 'MAIL': '/var/mail/cltbld', 11:27:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:42 INFO - 'MOZ_NO_REMOTE': '1', 11:27:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:42 INFO - 'NO_EM_RESTART': '1', 11:27:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:42 INFO - 'PWD': '/builds/slave/test', 11:27:42 INFO - 'SHELL': '/bin/bash', 11:27:42 INFO - 'SHLVL': '1', 11:27:42 INFO - 'TERM': 'linux', 11:27:42 INFO - 'TMOUT': '86400', 11:27:42 INFO - 'USER': 'cltbld', 11:27:42 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', 11:27:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:42 INFO - '_': '/tools/buildbot/bin/python'} 11:27:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:27:42 INFO - Running setup.py (path:/tmp/pip-7FZauA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:27:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:27:42 INFO - Running setup.py (path:/tmp/pip-7GNBoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:27:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:27:42 INFO - Running setup.py (path:/tmp/pip-jdFp5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:27:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:27:42 INFO - Running setup.py (path:/tmp/pip-3g5QXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:27:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:27:42 INFO - Running setup.py (path:/tmp/pip-Q4TomV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:27:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:27:42 INFO - Running setup.py (path:/tmp/pip-c7vOB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:27:43 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)) 11:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:27:43 INFO - Running setup.py (path:/tmp/pip-o5cj5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:27:43 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)) 11:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:27:43 INFO - Running setup.py (path:/tmp/pip-l4UqgR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:27:43 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)) 11:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:27:43 INFO - Running setup.py (path:/tmp/pip-hTZ7bP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:27:43 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)) 11:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:27:43 INFO - Running setup.py (path:/tmp/pip-OQgIEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:27:43 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)) 11:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:27:43 INFO - Running setup.py (path:/tmp/pip-XWQsSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:27:43 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)) 11:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:27:43 INFO - Running setup.py (path:/tmp/pip-Dlo_0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:27:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:27:43 INFO - Running setup.py (path:/tmp/pip-GYZUSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:27:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:27:43 INFO - Running setup.py (path:/tmp/pip-QI4y59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:27:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:27:43 INFO - Running setup.py (path:/tmp/pip-XoevlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:27:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:27:43 INFO - Running setup.py (path:/tmp/pip-JdqQdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:27:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:27:43 INFO - Running setup.py (path:/tmp/pip-9pkaee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:27:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:27:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:27:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:27:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:27:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:27:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:27:43 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:27:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:27:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:44 INFO - Downloading blessings-1.6.tar.gz 11:27:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:27:45 INFO - Installing collected packages: blessings 11:27:45 INFO - Running setup.py install for blessings 11:27:45 INFO - Successfully installed blessings 11:27:45 INFO - Cleaning up... 11:27:45 INFO - Return code: 0 11:27:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:27:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:27:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:27:45 INFO - Reading from file tmpfile_stdout 11:27:45 INFO - Current package versions: 11:27:45 INFO - argparse == 1.2.1 11:27:45 INFO - blessings == 1.6 11:27:45 INFO - blobuploader == 1.2.4 11:27:45 INFO - docopt == 0.6.1 11:27:45 INFO - functools32 == 3.2.3-2 11:27:45 INFO - jsonschema == 2.5.1 11:27:45 INFO - manifestparser == 1.1 11:27:45 INFO - mozInstall == 1.13 11:27:45 INFO - mozcrash == 1.0 11:27:45 INFO - mozdebug == 0.1 11:27:45 INFO - mozdevice == 0.51 11:27:45 INFO - mozfile == 1.2 11:27:45 INFO - mozhttpd == 0.7 11:27:45 INFO - mozinfo == 0.10 11:27:45 INFO - mozleak == 0.1 11:27:45 INFO - mozlog == 3.5 11:27:45 INFO - moznetwork == 0.27 11:27:45 INFO - mozprocess == 0.25 11:27:45 INFO - mozprofile == 0.28 11:27:45 INFO - mozrunner == 6.13 11:27:45 INFO - mozscreenshot == 0.1 11:27:45 INFO - mozsystemmonitor == 0.3 11:27:45 INFO - moztest == 0.8 11:27:45 INFO - mozversion == 1.4 11:27:45 INFO - psutil == 3.1.1 11:27:45 INFO - requests == 1.2.3 11:27:45 INFO - wsgiref == 0.1.2 11:27:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:27:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b6a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d32a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed6580>, '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 0x1ed7480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed7910>, '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': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', '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 11:27:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:27:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:27:45 INFO - Using env: {'DISPLAY': ':0', 11:27:45 INFO - 'HOME': '/home/cltbld', 11:27:45 INFO - 'LANG': 'en_US.UTF-8', 11:27:45 INFO - 'LANGUAGE': 'en_US:en', 11:27:45 INFO - 'LOGNAME': 'cltbld', 11:27:45 INFO - 'MAIL': '/var/mail/cltbld', 11:27:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:45 INFO - 'MOZ_NO_REMOTE': '1', 11:27:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:45 INFO - 'NO_EM_RESTART': '1', 11:27:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:45 INFO - 'PWD': '/builds/slave/test', 11:27:45 INFO - 'SHELL': '/bin/bash', 11:27:45 INFO - 'SHLVL': '1', 11:27:45 INFO - 'TERM': 'linux', 11:27:45 INFO - 'TMOUT': '86400', 11:27:45 INFO - 'USER': 'cltbld', 11:27:45 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', 11:27:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:45 INFO - '_': '/tools/buildbot/bin/python'} 11:27:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:27:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:27:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:27:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:27:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:27:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:27:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:27:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:27:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:27:45 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:27:46 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:27:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:27:46 INFO - Installing collected packages: simplejson 11:27:46 INFO - Running setup.py install for simplejson 11:27:46 INFO - building 'simplejson._speedups' extension 11:27:46 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 11:27:47 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 11:27:47 INFO - Successfully installed simplejson 11:27:47 INFO - Cleaning up... 11:27:47 INFO - Return code: 0 11:27:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:27:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b6a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d32a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed6580>, '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 0x1ed7480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed7910>, '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': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', '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 11:27:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:27:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:27:47 INFO - Using env: {'DISPLAY': ':0', 11:27:47 INFO - 'HOME': '/home/cltbld', 11:27:47 INFO - 'LANG': 'en_US.UTF-8', 11:27:47 INFO - 'LANGUAGE': 'en_US:en', 11:27:47 INFO - 'LOGNAME': 'cltbld', 11:27:47 INFO - 'MAIL': '/var/mail/cltbld', 11:27:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:47 INFO - 'MOZ_NO_REMOTE': '1', 11:27:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:47 INFO - 'NO_EM_RESTART': '1', 11:27:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:47 INFO - 'PWD': '/builds/slave/test', 11:27:47 INFO - 'SHELL': '/bin/bash', 11:27:47 INFO - 'SHLVL': '1', 11:27:47 INFO - 'TERM': 'linux', 11:27:47 INFO - 'TMOUT': '86400', 11:27:47 INFO - 'USER': 'cltbld', 11:27:47 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', 11:27:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:47 INFO - '_': '/tools/buildbot/bin/python'} 11:27:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:27:48 INFO - Cleaning up... 11:27:48 INFO - Return code: 0 11:27:48 INFO - Running post-action listener: _resource_record_post_action 11:27:48 INFO - Running post-action listener: _start_resource_monitoring 11:27:48 INFO - Starting resource monitoring. 11:27:48 INFO - [mozharness: 2017-08-31 18:27:48.105640Z] Finished create-virtualenv step (success) 11:27:48 INFO - [mozharness: 2017-08-31 18:27:48.106160Z] Running install step. 11:27:48 INFO - Running pre-action listener: _resource_record_pre_action 11:27:48 INFO - Running main action method: install 11:27:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:27:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:27:48 INFO - Reading from file tmpfile_stdout 11:27:48 INFO - Detecting whether we're running mozinstall >=1.0... 11:27:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:27:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:27:48 INFO - Reading from file tmpfile_stdout 11:27:48 INFO - Output received: 11:27:48 INFO - Usage: mozinstall [options] installer 11:27:48 INFO - Options: 11:27:48 INFO - -h, --help show this help message and exit 11:27:48 INFO - -d DEST, --destination=DEST 11:27:48 INFO - Directory to install application into. [default: 11:27:48 INFO - "/builds/slave/test"] 11:27:48 INFO - --app=APP Application being installed. [default: firefox] 11:27:48 INFO - mkdir: /builds/slave/test/build/application 11:27:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:27:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:28:04 INFO - Reading from file tmpfile_stdout 11:28:04 INFO - Output received: 11:28:04 INFO - /builds/slave/test/build/application/firefox/firefox 11:28:04 INFO - Running post-action listener: _resource_record_post_action 11:28:04 INFO - [mozharness: 2017-08-31 18:28:04.979291Z] Finished install step (success) 11:28:04 INFO - [mozharness: 2017-08-31 18:28:04.979440Z] Running setup-mitmproxy step. 11:28:04 INFO - Running pre-action listener: _resource_record_pre_action 11:28:04 INFO - Running main action method: setup_mitmproxy 11:28:04 INFO - Skipping: mitmproxy is not required 11:28:04 INFO - Running post-action listener: _resource_record_post_action 11:28:04 INFO - [mozharness: 2017-08-31 18:28:04.980132Z] Finished setup-mitmproxy step (success) 11:28:04 INFO - [mozharness: 2017-08-31 18:28:04.980265Z] Running run-tests step. 11:28:04 INFO - Running pre-action listener: _resource_record_pre_action 11:28:04 INFO - Running pre-action listener: _set_gcov_prefix 11:28:04 INFO - Running main action method: run_tests 11:28:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:28:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:28:04 INFO - Python 2.7.3 11:28:04 INFO - Return code: 0 11:28:04 INFO - grabbing minidump binary from tooltool 11:28:04 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')]} 11:28:04 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 0x1ed6580>, '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 0x1ed7480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed7910>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:28:04 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 11:28:04 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 11:28:04 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 11:28:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:28:05 INFO - Return code: 0 11:28:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:28:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:28:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:28:05 INFO - ENV: RUST_BACKTRACE is now full 11:28:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:28:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:28:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-079', '--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 11:28:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-079 --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 11:28:05 INFO - Using env: {'DISPLAY': ':0', 11:28:05 INFO - 'HOME': '/home/cltbld', 11:28:05 INFO - 'LANG': 'en_US.UTF-8', 11:28:05 INFO - 'LANGUAGE': 'en_US:en', 11:28:05 INFO - 'LOGNAME': 'cltbld', 11:28:05 INFO - 'MAIL': '/var/mail/cltbld', 11:28:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:28:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:28:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:05 INFO - 'MOZ_NO_REMOTE': '1', 11:28:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:28:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:28:05 INFO - 'NO_EM_RESTART': '1', 11:28:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:28:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:05 INFO - 'PWD': '/builds/slave/test', 11:28:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:28:05 INFO - 'RUST_BACKTRACE': 'full', 11:28:05 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:28:05 INFO - 'SHELL': '/bin/bash', 11:28:05 INFO - 'SHLVL': '1', 11:28:05 INFO - 'TERM': 'linux', 11:28:05 INFO - 'TMOUT': '86400', 11:28:05 INFO - 'USER': 'cltbld', 11:28:05 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', 11:28:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:05 INFO - '_': '/tools/buildbot/bin/python'} 11:28:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-079', '--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 11:28:05 INFO - mozversion application_buildid: 20170831172745 11:28:05 INFO - mozversion application_changeset: 4984da22242841a5d84c4e5fd866e93a450d9723 11:28:05 INFO - mozversion application_display_name: Nightly 11:28:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:28:05 INFO - mozversion application_name: Firefox 11:28:05 INFO - mozversion application_remotingname: firefox 11:28:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:28:05 INFO - mozversion application_vendor: Mozilla 11:28:05 INFO - mozversion application_version: 57.0a1 11:28:05 INFO - mozversion platform_buildid: 20170831172745 11:28:05 INFO - mozversion platform_changeset: 4984da22242841a5d84c4e5fd866e93a450d9723 11:28:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:28:05 INFO - mozversion platform_version: 57.0a1 11:28:05 INFO - using testdate: 1504204085 11:28:05 INFO - actual date: 1504204085 11:28:05 INFO - starting webserver on 'localhost:54507' 11:28:05 INFO - SUITE-START | Running 2 tests 11:28:05 INFO - TEST-START | dromaeo_css 11:28:05 INFO - Initialising browser for dromaeo_css test... 11:28:05 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54507/getInfo.html -profile /tmp/tmpVX37ll/profile 11:28:05 INFO - TEST-INFO | started process 13874 (/builds/slave/test/build/application/firefox/firefox http://localhost:54507/getInfo.html) 11:28:10 INFO - TEST-INFO | 13874: exit 0 11:28:11 INFO - Browser initialized. 11:28:11 INFO - Running cycle 1/1 for dromaeo_css test... 11:28:11 INFO - Using env: {'DISPLAY': ':0', 11:28:11 INFO - 'HOME': '/home/cltbld', 11:28:11 INFO - 'JSGC_DISABLE_POISONING': '1', 11:28:11 INFO - 'LANG': 'en_US.UTF-8', 11:28:11 INFO - 'LANGUAGE': 'en_US:en', 11:28:11 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:28:11 INFO - 'LOGNAME': 'cltbld', 11:28:11 INFO - 'MAIL': '/var/mail/cltbld', 11:28:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:28:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:28:11 INFO - 'MOZ_CRASHREPORTER': '1', 11:28:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:11 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:28:11 INFO - 'MOZ_NO_REMOTE': '1', 11:28:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:28:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:28:11 INFO - 'NO_EM_RESTART': '1', 11:28:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:28:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:11 INFO - 'PWD': '/builds/slave/test', 11:28:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:28:11 INFO - 'RUST_BACKTRACE': 'full', 11:28:11 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:28:11 INFO - 'SHELL': '/bin/bash', 11:28:11 INFO - 'SHLVL': '1', 11:28:11 INFO - 'TERM': 'linux', 11:28:11 INFO - 'TMOUT': '86400', 11:28:11 INFO - 'USER': 'cltbld', 11:28:11 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', 11:28:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:11 INFO - '_': '/tools/buildbot/bin/python'} 11:28:11 INFO - TEST-INFO | started process 14055 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVX37ll/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:28:12 INFO - PID 14055 | 11:28:12 INFO - PID 14055 | (/builds/slave/test/build/application/firefox/firefox:14106): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:28:12 INFO - PID 14055 | 11:28:22 INFO - PID 14055 | RSS: Main: 169598976 11:28:22 INFO - PID 14055 | 11:31:21 INFO - PID 14055 | Cycle 1(1): loaded http://localhost:54507/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54507/tests/dromaeo/cssquery-ext.html) 11:31:22 INFO - PID 14055 | RSS: Main: 171085824 11:31:22 INFO - PID 14055 | 11:34:24 INFO - PID 14055 | Cycle 1(1): loaded http://localhost:54507/tests/dromaeo/cssquery-ext.html (next: http://localhost:54507/tests/dromaeo/cssquery-jquery.html) 11:34:24 INFO - PID 14055 | RSS: Main: 168579072 11:34:24 INFO - PID 14055 | 11:37:26 INFO - PID 14055 | Cycle 1(1): loaded http://localhost:54507/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54507/tests/dromaeo/cssquery-mootools.html) 11:37:26 INFO - PID 14055 | RSS: Main: 171417600 11:37:26 INFO - PID 14055 | 11:40:28 INFO - PID 14055 | Cycle 1(1): loaded http://localhost:54507/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54507/tests/dromaeo/cssquery-prototype.html) 11:40:29 INFO - PID 14055 | RSS: Main: 169529344 11:40:29 INFO - PID 14055 | 11:43:32 INFO - PID 14055 | Cycle 1(1): loaded http://localhost:54507/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54507/tests/dromaeo/cssquery-yui.html) 11:43:33 INFO - PID 14055 | RSS: Main: 168722432 11:43:33 INFO - PID 14055 | 11:46:35 INFO - PID 14055 | Cycle 1(1): loaded http://localhost:54507/tests/dromaeo/cssquery-yui.html (next: http://localhost:54507/tests/dromaeo/cssquery-dojo.html) 11:46:35 INFO - PID 14055 | RSS: Main: 172810240 11:46:35 INFO - PID 14055 | 11:46:35 INFO - PID 14055 | __start_tp_report 11:46:35 INFO - PID 14055 | _x_x_mozilla_page_load 11:46:35 INFO - PID 14055 | _x_x_mozilla_page_load_details 11:46:35 INFO - PID 14055 | |i|pagename|runs| 11:46:35 INFO - PID 14055 | |0;dojo.html;3308.8511017004234;3312.403767321882;3317.933641327173;3344.8996530104105;3361.3613413451426;6357.459615932639;6358.491320694345;6379.393957574029;6409.551331406803;6410.038494225862;6244.812655620327;6320.146780184674;6332.651704156269;6333.113364129025;6342.556021078526;5773.884522309561;5777.1623114648355;5779.422057794208;5791.7972870949525;5848.941510584904;5837.678927658977;5876.823695289127;5914.378990206031;5929.991901376755;5933.199018132562;5794.130880367937;5864.091065884794;5876.676782776945;5888.146218798272;5950.5834591578605;5643.492085712305;5769.105788602731;5876.55925805562;5924.644521328734;5925.318588362329;5528.253685752412;5772.163036359704;5831;5834.5624078194305;5859.4140585941495;6224.190855188842;6321.87356252877;6367.713452894631;6370.1718776558755;6412.743490260431;6519.380658837403;6617.073609694647;6743.561668491532;6788.524803263774;6832.214295355998;6845.14435695538;6851.794446166623;6870.725170993154;6891.241963384024;6898.517103802736;6328.208973878265;6411.711472983727;6460.806175814686;6465.418112369909;6466;6959.930400696029;7017.859642807115;7035.718571257093;7058.364747172779;7123.572585644878;6378.649174295366;6608.504362172856;6675.699593518206;6715.2613212546585;6813.863722725518;6801.795946121624;6849.863002740017;6886.586804791628;6886.965565172142;6922.342377474132;6803.65981700913;6872.553284036521;6879.759208427681;6885;6887.242403335629;6793.980902864511;6800.625965571941;6809.353111454404;6904.067950826574;6920.5847649141215;5242.318498595158;5304.151335786333;5314.202869569518;5316.468353164652;5322.33470816148;4633.7683115844075;4671.649626278043;4706.717596944194;4712.88217794551;4770.165221086296;5663.122216056518;5693.658380497183;5781.161731548902;5788.507976821933;5791.102379131242;6620.5365624406795;6631.966840165865;6635.203775546869;6640.335966403321;6641.568298060611;5768.105943578752;5776.537876969833;5778.682172480554;5783.450572195635;5789.102689083199;5692.430756924275;5721.485066344049;5820.417958204146;5877.38287479817;5905.911411328833;6644.700988455531;6694.832629184212;6708.463322934153;6708.832279192961;6740.29226931175;6817.284185160586;6830;6842.349976752201;6912.308769123148;6928.237893831573;6449.677516124081;6551.148350714377;6610.305917878553;6674.365935236145;6702.497312701665;6631.602103873783;6709.094272273376;6729.2934241903895;6733.528652994243;6754.459643228432;6792.490563207878;6794.116764820542;6886.827829304107;6888.448924086161;6893.414059804973;6493.8701225976165;6545.280019197789;6580.5722628029025;6635.23694775107;6644.966775166094;6421.229452465734;6425.45383642377;6484.935150648622;6499;6567.211934567882;6471;6489.253735820253;6547.345265473605;6600.702968366531;6648.069270302065;6629.668516574248;6686.465082793462;6740.292269311652;6763.3236676333945;6776.457883369417;6153.507719382349;6253.343398943045;6390.968045159744;6441.194850643669;6542.280349161493 11:46:35 INFO - PID 14055 | |1;ext.html;817.7424111404907;865.1348651348651;881.4711173296023;882.2324577617474;1125.4766533561892;30421.543676844864;40830.79584602077;42190.15619687608;42201.36697949533;42240;42265.57734422662;42580.14839703204;42593.574064259345;42632.14735705284;42699.359509607384;7815.374770018385;7825.491343062697;7950.1254861965135;7952.324052455549;7954.761357159295;7812.6093695315;7879.290863822255;7888.881666775003;7895.881561776578;7914.762557123267;7885.29032387085;7937.44437889348;7938.523688578675;7940.761777146695;7942.126366099725;7874.803129921741;7879.251471110293;7881.172476889901;7893.013373328363;7897.526148431112;7764.767056988299;7798.37612990965;7812.1016083150025;7818.14000459949;7819.530828150329;38528.22943541114;38664.226715465535;38724.80637596794;38764.80617596894;38883.8055809722;3539.2213712983103;3571.8214089295693;3577.7853328800356;3579.7494175407733;3582.6238244984424;31618.841905790323;31645.84177079123;31701.365972680647;31729.206769830707;31750.523742143887;5081.872953176163;5219.765110569995;5226.5818734501145;5249.632525723201;5254.106801843695;192379;194436;195358.02320988447;195544;195789;48301.75849120732;48487.75756121197;48572.7571362148;48713.512864871605;48713.75643121762;50614.493855061715;50749;50772.74613626908;50826;50867.491325087016;12938.805917911239;15221.847781522265;15471.845281547265;15479.458218962282;15490.07059576429;10447.373157610567;12187.817182742267;12320.81518777219;12321.075919306088;12406.689832754253;14685.19231442281;14719.779203311959;14740.894432917574;14772.113673179643;14798.260086995822;5751.827445176651;5886.911696324559;5890.4698577128265;5935.554833387513;6059.848503787441;998.9850152247722;1012.6455740490769;1013.7212266626736;1024.4877561219391;1025.9846102308472;22821.087156513884;28338.858305708338;28346.43307133846;28777.561121943818;28949.565756513668;1074.511097450658;1091.770728147098;1096.3586302013252;1097.9725506862392;1113.615802548136;12518.06114541413;12668.936655316664;12683.936580317039;12758.744825103446;12900.35498225085;226866;229894;229912;230289;231129;52444;53214.46785532172;53223.467765322624;53240.46759532433;53607;1424.4088703188268;1442.6753980354335;1447.8407375188722;1451.7241724072392;1464.7143806957754;22806.31581052571;22808;22913.770862291163;22956.540869182856;23184.53630927406;35426;35582.644173557936;35608.643913561566;35649.46525802063;35725.46411803773;203270;203509;204277;204443;204545;34581.48127778136;36840.63159368372;37678;37830.62169378271;37855.43216851805;45965;46135.53864461447;46172.538274616825;46519.06961860812;46726;33670.32659346848;33905.49141762925;34156.65843341634;34256.486152708225;34613.65386346205;33312.50031249582;33314.66685333115;33571.83214083914;33601.83199083989;34173.82913085419;37388.25223495569;37467.2506549862;37621.81189094037;37714.43428348632;37939.620603793606;32858.17854553559;33284.33431331311;33504.497432538046;33514.497282540295;34049;24170.637440438055;24208.63687044731;24305.39236519101;24654.01383944694;25008.249752507454 11:46:35 INFO - PID 14055 | |2;jquery.html;571.6684323092607;573.790566443248;574.4227051812927;592.2715060475616;594.8156071617798;22378.10487580497;22690;23263;23265.651015234766;23286.18498352556;24949;25384.87307563464;25488.87255563724;25528.872355638243;25555.87222063887;6512.934870651304;6662.666866656672;6674.56615320005;6678.232003319632;6678.833029174261;6013.879722405549;6477.773277935274;6507.934920650804;6560.835979100537;6572.967135164317;6450.096986421881;6457.547971641963;6507.3492650735025;6516.283208847023;6527.281998980108;6440.516961227903;6446.194225721785;6485.059666348377;6497.545171837974;6503.382178693017;6044.123602077718;6046.727897244635;6049.425304596057;6075.605085669417;6077.20996270482;28825.27936801576;29374.265643358875;29887.252818679706;30025.249368765737;30073.097807065824;1948.133080779054;1966.4887129346473;1980.3959792263354;1981.0292956451378;1982.0189006441904;19381.51546211356;19908.90045549763;20012.899935500376;20015.299464518674;20085.39743807688;2376.6553849691873;2435.7942818304873;2489.6763420755187;2493.4265119022543;2504.8998040078377;132999;138678.99999999898;138771;139727;139814.30092849574;14244.290685117852;14343.641408964651;14450.855491445162;14633.512302908532;15167.393304267705;22910.541789164123;23043.308700739006;23086.884565577402;23091.422714432272;23093.88453057724;9713.951430242803;9868.851967220497;9951.303408761318;9998.80002399948;10116.241281903887;7672.616369181518;9200.90799092014;9240.3531752778;9268.675596354095;9308.44149351041;8010.5994700264755;8998.325125615605;9028.954855225813;9047.276217902552;9288.767780805534;2619.6594442722703;2638.61740047692;2649.9337516561854;2787.9303017424727;2802.943941121207;955.4458414119794;966.6278482784179;971.6307803034942;982.8083523712951;991.3952488981715;19127.2349106037;19192.328268510537;19319.710204346946;19630;19780.90109549443;1013.6604237580493;1034.131329683055;1036.6009086501601;1039.1063685230695;1041.245097304448;10892;10919.235653504338;10931;10953.34279943206;11095.945885140898;436440;442734;456636;464373;466443;107029;107828.46085769522;108763;108960;109287.45356273168;1165.6328256599145;1180.8110702287593;1184.5261895241972;1193.4569770754285;1202.5370232460566;17274.481765547054;17550.297988080332;17640;17760.644787103927;17867.553311166805;54293.72853135709;55086.72456637691;55869.44130558642;57272.14091788543;57571;69736.30263697298;70530.64734676294;70765.23086922662;70862;71742.92385614116;14156.292185390896;14184.219867907364;14284;14403.495877644233;14499.420023198954;22732.772672273066;22856.885715571316;22961.655575166053;23106;23123.65314520317;17225;18481.630367392307;18724.6255074895;18724.970126643166;18744.906275468536;16377.672446550763;16657.83342166563;17017.149142543072;17072.146392680068;17132.657346852742;17935.282588696828;18285.908570457064;18313.3590324338;18315.54211144732;18463.79985300951;13957.302134893418;13976.510822121178;14146.575602731935;14159.221242831743;14166.716665666836;18024.90987545054;18071.909640451715;18312;18324.725129122806;18402.539936501693 11:46:35 INFO - PID 14055 | |3;mootools.html;1351.5539871842293;1388.881945034672;1403.8596140385957;1404.3329418526207;1406.0860440713532;14917.552473425802;15862.920685396586;15872.365105395786;15892.84107158928;15905.681886362267;15279.770803437958;15567.610809729762;15603.141827199499;15604.219789010533;15606.765898511503;2754.5317296059716;2796.2310364649784;2797.692253852075;2799.650043744532;2799.8040137190405;2674.7993900457445;2697.4874773793013;2732.699403065661;2744.821586596875;2748.2854457840904;2698.743619356168;2714.267147870074;2742.862856857159;2783.832970021795;2792.706765789593;2739.671239451269;2770.362816552194;2770.459760346733;2772.306923269183;2772.473230086277;2632.578787394027;2666.6000099984963;2666.7199944005793;2676.745709157627;2680.584509401046;14647.780283295759;14948.40206391743;14954;14986.925065374713;14999.775003374958;7902.723404680866;8179.4274400791965;8183.754487365389;8204.548749818758;8205.671773129068;5350.8394748157225;5550.27846379968;5562.360328562227;5728.169415434739;5745.454181852718;1843.511469460594;1922.1734654098732;2222.655488399301;2286.8970896309706;2292.2321022457495;27252;27831.582526262122;27831.86084069587;27879.581806273123;27904.441911161663;7070.575765454089;7087.539309944837;7171.748988785367;7297.635118244067;7303.160136584254;19718.90140549288;19724.802751972584;19725.901370493055;19729.90135049344;19740.605187896163;8183.263506284463;8302.460340077876;8319.875201871977;8322.042965059096;8336.333093352518;7306.744263950736;7313.524620899624;7344.522606030698;7429.739959101514;7437.256274372519;5560.888782224414;5666.9716651416475;5727.398623144593;5752.683602401824;5787.739551720183;1739.9304027838998;1791.1939627167826;1802.0809387212616;1835.5686413692772;1841.5027942455463;813.7436707437257;832.3965125284242;848.3510114762212;851.3784936996028;866.4584634603373;7172.892406613905;7188.4968052235845;7223.097112860893;7241.637918104074;7261.89107163393;701.8876979683226;706.5548704316247;708.4190963409955;712.9180144283474;715.3526058916689;5095.31213286209;5776.913346299809;5785.623934444247;5887.646741195542;6028.186194863637;21606.891965540388;21811.454713631967;22234.221802236843;22264;22325.665115023286;19897.403077907686;21289.36131916045;21299.574008519743;21562.353129406143;21694.13223471075;9425.952870235742;9708.90291097094;10870.456477176267;11012.779744405067;11193.10455163585;8838.513881736566;9045.185933265775;9109.635614575343;9113.589888455168;9305.860412093689;18438.26246950107;18671.533211669816;18727.344542940933;19011.429657110308;19315;18253.54366140804;18723.25106995705;19191.808081919004;19291.710624340925;19481.220751169796;11368.090552755595;11769.058475322125;11770.76458470843;11777.587784427504;11804.114691398352;13819.792703109662;13878.930605347312;14009.859901400856;14044;14058.086224395382;9502.952485237529;9520.190783783455;9555.665551705657;9644.421334719938;9694.709158725249;10801.297915635458;10864;10876.619318323821;11080.390578517936;11146.164037697043;9357.672481463116;9471.47906865129;9481.146696797045;9481.241500679791;9494.335396522178;7151.356377926011;7178.318059784521;7254.746083887038;7258.49190556656;7348.853022939405;7386.891966205112;7396.593187374746;7426.517276377018;7462.946817785528;7552.773416797505 11:46:35 INFO - PID 14055 | |4;prototype.html;505.19169329073486;506.1168261383888;529.4387948774297;534.2733881920587;539.2046731071667;6269.49844012479;6285.522902118004;6339.778107766229;6342.682865856712;6355.332690067546;5924.170616113747;5981.4018598140165;6162.22972128484;6195.349488303719;6197.132401463788;5006.649534532584;5037.294778730962;5042.495750424947;5044.974775126128;5070.29015937769;4820.614350851941;5007.724575148365;5024.120778863702;5039.798408063692;5051.747412629372;4983.551480366767;4987.875303117433;4989.401271847383;5037.49625037497;5051.924221136685;4922.90154196914;4932.704037757746;4942.851714448572;5003.374578177728;5032.949670503303;4443.688941774077;4478.305862591271;4485.304777759453;4490.079533695584;4492.595666390041;6109.389061093855;6170.167027451326;6179.196704428396;6204.937950620481;6225.066240063981;5675.269042941901;5683.175939488751;5715.685637289947;5717.656940583578;5729;6452.354764523557;6502.90245646311;6539.411452969208;6585.9012114818315;6588.341165883421;4815.229563269895;4845.3216549683075;4858.271259311096;4862.3922009748785;4889.1443997300685;6880.965595172042;6902.413294869942;6914.9654251728925;6940.236573976858;6987.7204911803465;4915.083117584145;4940.802367905244;4958.140697238536;4962.503749625081;4983.103041452501;4997;5019.42276638184;5058.494150584986;5060.089183946851;5080.263361812516;5673.546116310686;5691.8292451226525;5714.257146570919;5749.453801888814;5755.22304488897;6512.479001679854;6516.152900123048;6530.8040758777315;6587.440067594212;6634.767783127568;1664.617138058229;1714.579927917668;1719.7420386941808;1726.3526177683368;1729.2391347806924;1200.255841378351;1202.699325168708;1219.7560487902279;1220.993895030537;1238.9876101238872;5762.2509073820975;5772.191893134965;5803.506701930383;5808.128780682931;5815.912761308584;6754.628495432798;6779.5254332197255;6783.491238157158;6796.35434633709;6801.251862295144;5660.915086273709;5780.1907732917425;5803.129530570363;5839.386864379264;5852.795152169739;3561.895812298196;3569.5716514018486;3581.0510214793103;3586.0138461922656;3595.1910820065277;6511.413972742476;6517.543771935919;6523.804285871432;6530.510211734139;6588.736450541925;6827;6928.237893831674;6953.130858642669;6975.8604827903155;6988.126484189476;6525.477961763143;6542.789583926989;6548.8362790930605;6568.86862262752;6584.604923704497;6780.32196780318;6806.285340039422;6808;6822.556533825285;6829.419499342612;6548.672566371567;6737.157855268091;6737.3599508046655;6752.797416077526;6796.59220446755;6488.221413430419;6562.949928011495;6566.2120545532935;6625.801225963036;6688.632125232964;4952.678075925053;4960.479149689375;4975.179095449355;5008.198688209869;5010.549050585465;6760.256371799;6770.93229067703;6859.314068593101;6870.862582748217;6891.862162756817;6421.935780642134;6446.258680251831;6516.771912983023;6541.215054193527;6575.901361479678;6475.158229430144;6512.511561632802;6536.8365790855605;6546.279909209888;6585.275619681735;6399.136116624382;6430.260520040255;6461.450776684034;6507.381798729113;6513.316101809241;6364.204474440695;6401.743930242738;6423.743050277936;6447;6639.634820084942;6227.408396202353;6262.2172228471445;6277.529185311028;6309.400606942333;6431.614103153639 11:46:35 INFO - PID 14055 | |5;yui.html;884.1114679746853;890.483519558656;901.0898992018061;905.4476769170809;912.6440688131626;16823.49529514115;17190.74213886791;17204.48386548404;17255.482335529923;20633.277835275767;18804.811951880478;19762.505937351536;19813.40559783205;19833.603327933433;19899.30352437665;922.9169374756287;974.4348277998744;987.1362557761959;989.5547003848261;989.7327721515187;1006.9728876545919;1007.3351587951953;1007.591925270263;1007.6372505897871;1010.777628921636;987.2644879564734;1010.1817527802467;1013.2046343620223;1015.5836107196051;1069.187417562654;990.6829814459376;991.4447909170851;991.8413053911376;992.8907820139779;994.9204063674889;732.3555271361221;736.5654263984221;736.6869080640706;737.4284929179875;737.9667914943841;19429.9028504858;19796.70304945427;20159.39521814348;20164.19343226269;20497.4875628109;21178;21384.25155119579;21655;21657.45856353588;21900.561988760135;17119.74320385195;17361.392351267772;17385.304587816474;17528.737068943978;17603.559911002198;3750.043738846604;3790.4124860646643;3791.848326066954;3796.886093417202;3801.6958643308467;128936;128977.35511322477;128997;133190.3340483301;133453;15627.765583516257;16102.838971610134;16172.353105875869;16273;16611.91694041522;30338;30703.385932281228;30772.384552309275;31587.36825263528;31688.841555792536;6592.901106483407;6881.621510816853;8147.348212143014;8256.958715206505;8258.752237432887;4326.069894972596;4409.0520538084465;4437.201303765289;4437.245668236407;4439.467263928349;5238.423773384925;5317.441668624816;5347.51872331492;5362.1420572708175;5669.376368599451;1092.808758467265;1111.4998250787178;1129.5651174297955;1129.740159763259;1131.4003578103618;687.1273482677072;698.9266127056586;706.4242642246553;707.8053535277737;711.5979471598633;11759.47082381295;11793.820617938138;11931.1051671125;12060.939695301468;13208.141470804367;695.45059403071;695.952591350015;700.1563116444672;701.551007355293;708.8972099045609;5268.28878101459;5445.754941027663;5466.480684334982;5519.365272993577;5813.79651712188;100741;104788.47605761922;106974;107718;108133.45933270283;91250.54374728084;93988.53005735064;94782;96234.51882740541;96698;81523;82709;83462.58268708618;84808.57595711981;87343.56328218318;10472.319299245524;10536.578536858746;10642.574297028343;10859.2398532102;10902.672919812418;41994;42629;43209.56790432181;43403.9149021277;44531.10937781291;52518.474815252885;71395.64302178664;71879;72479.27520724725;74722;18144.909275453538;18409.355672551395;18668.906655466635;18668.906655466635;18693.626127477648;15630.374785008928;16114.113723745306;16143.596410089842;18587.25650974;20199.990000499623;18400.35598754091;18488.815111848708;18768.343107991153;18803.529911751768;18805.435836924367;18280.72578911344;18507.814921851146;18628;18702;18775.34286299973;16303;17115.572110697332;18285.71999960043;18843.90578047101;19000.23999040023;11890.643280701594;12330.568430104904;12725.745485090432;12769.936150319189;12857.807132893202;14505.347259373142;14578.781318280446;14741;14798.852011479747;14928.477503287333 11:46:35 INFO - PID 14055 | __end_tp_report 11:46:35 INFO - PID 14055 | __start_cc_report 11:46:35 INFO - PID 14055 | _x_x_mozilla_cycle_collect,388 11:46:35 INFO - PID 14055 | __end_cc_report 11:46:35 INFO - PID 14055 | __startTimestamp1504205195547__endTimestamp 11:46:35 INFO - PID 14055 | 11:46:35 INFO - PID 14055 | ------- Summary: start ------- 11:46:35 INFO - PID 14055 | Number of tests: 6 11:46:35 INFO - PID 14055 | 11:46:35 INFO - PID 14055 | [#0] dojo.html Cycles:165 Average:6247.09 Median:6466.00 stddev:740.72 (11.5%) stddev-sans-first:706.22 11:46:35 INFO - PID 14055 | Values: 3308.9 3312.4 3317.9 3344.9 3361.4 6357.5 6358.5 6379.4 6409.6 6410.0 6244.8 6320.1 6332.7 6333.1 6342.6 5773.9 5777.2 5779.4 5791.8 5848.9 5837.7 5876.8 5914.4 5930.0 5933.2 5794.1 5864.1 5876.7 5888.1 5950.6 5643.5 5769.1 5876.6 5924.6 5925.3 5528.3 5772.2 5831.0 5834.6 5859.4 6224.2 6321.9 6367.7 6370.2 6412.7 6519.4 6617.1 6743.6 6788.5 6832.2 6845.1 6851.8 6870.7 6891.2 6898.5 6328.2 6411.7 6460.8 6465.4 6466.0 6959.9 7017.9 7035.7 7058.4 7123.6 6378.6 6608.5 6675.7 6715.3 6813.9 6801.8 6849.9 6886.6 6887.0 6922.3 6803.7 6872.6 6879.8 6885.0 6887.2 6794.0 6800.6 6809.4 6904.1 6920.6 5242.3 5304.2 5314.2 5316.5 5322.3 4633.8 4671.6 4706.7 4712.9 4770.2 5663.1 5693.7 5781.2 5788.5 5791.1 6620.5 6632.0 6635.2 6640.3 6641.6 5768.1 5776.5 5778.7 5783.5 5789.1 5692.4 5721.5 5820.4 5877.4 5905.9 6644.7 6694.8 6708.5 6708.8 6740.3 6817.3 6830.0 6842.3 6912.3 6928.2 6449.7 6551.1 6610.3 6674.4 6702.5 6631.6 6709.1 6729.3 6733.5 6754.5 6792.5 6794.1 6886.8 6888.4 6893.4 6493.9 6545.3 6580.6 6635.2 6645.0 6421.2 6425.5 6484.9 6499.0 6567.2 6471.0 6489.3 6547.3 6600.7 6648.1 6629.7 6686.5 6740.3 6763.3 6776.5 6153.5 6253.3 6391.0 6441.2 6542.3 11:46:35 INFO - PID 14055 | 11:46:35 INFO - PID 14055 | [#1] ext.html Cycles:180 Average:38296.59 Median:35629.05 stddev:54348.44 (152.5%) stddev-sans-first:54428.04 11:46:35 INFO - PID 14055 | Values: 817.7 865.1 881.5 882.2 1125.5 30421.5 40830.8 42190.2 42201.4 42240.0 42265.6 42580.1 42593.6 42632.1 42699.4 7815.4 7825.5 7950.1 7952.3 7954.8 7812.6 7879.3 7888.9 7895.9 7914.8 7885.3 7937.4 7938.5 7940.8 7942.1 7874.8 7879.3 7881.2 7893.0 7897.5 7764.8 7798.4 7812.1 7818.1 7819.5 38528.2 38664.2 38724.8 38764.8 38883.8 3539.2 3571.8 3577.8 3579.7 3582.6 31618.8 31645.8 31701.4 31729.2 31750.5 5081.9 5219.8 5226.6 5249.6 5254.1 192379.0 194436.0 195358.0 195544.0 195789.0 48301.8 48487.8 48572.8 48713.5 48713.8 50614.5 50749.0 50772.7 50826.0 50867.5 12938.8 15221.8 15471.8 15479.5 15490.1 10447.4 12187.8 12320.8 12321.1 12406.7 14685.2 14719.8 14740.9 14772.1 14798.3 5751.8 5886.9 5890.5 5935.6 6059.8 999.0 1012.6 1013.7 1024.5 1026.0 22821.1 28338.9 28346.4 28777.6 28949.6 1074.5 1091.8 1096.4 1098.0 1113.6 12518.1 12668.9 12683.9 12758.7 12900.4 226866.0 229894.0 229912.0 230289.0 231129.0 52444.0 53214.5 53223.5 53240.5 53607.0 1424.4 1442.7 1447.8 1451.7 1464.7 22806.3 22808.0 22913.8 22956.5 23184.5 35426.0 35582.6 35608.6 35649.5 35725.5 203270.0 203509.0 204277.0 204443.0 204545.0 34581.5 36840.6 37678.0 37830.6 37855.4 45965.0 46135.5 46172.5 46519.1 46726.0 33670.3 33905.5 34156.7 34256.5 34613.7 33312.5 33314.7 33571.8 33601.8 34173.8 37388.3 37467.3 37621.8 37714.4 37939.6 32858.2 33284.3 33504.5 33514.5 34049.0 24170.6 24208.6 24305.4 24654.0 25008.2 11:46:35 INFO - PID 14055 | 11:46:35 INFO - PID 14055 | [#2] jquery.html Cycles:180 Average:33436.78 Median:23099.94 stddev:76777.55 (332.4%) stddev-sans-first:76953.28 11:46:35 INFO - PID 14055 | Values: 571.7 573.8 574.4 592.3 594.8 22378.1 22690.0 23263.0 23265.7 23286.2 24949.0 25384.9 25488.9 25528.9 25555.9 6512.9 6662.7 6674.6 6678.2 6678.8 6013.9 6477.8 6507.9 6560.8 6573.0 6450.1 6457.5 6507.3 6516.3 6527.3 6440.5 6446.2 6485.1 6497.5 6503.4 6044.1 6046.7 6049.4 6075.6 6077.2 28825.3 29374.3 29887.3 30025.2 30073.1 1948.1 1966.5 1980.4 1981.0 1982.0 19381.5 19908.9 20012.9 20015.3 20085.4 2376.7 2435.8 2489.7 2493.4 2504.9 132999.0 138679.0 138771.0 139727.0 139814.3 14244.3 14343.6 14450.9 14633.5 15167.4 22910.5 23043.3 23086.9 23091.4 23093.9 9714.0 9868.9 9951.3 9998.8 10116.2 7672.6 9200.9 9240.4 9268.7 9308.4 8010.6 8998.3 9029.0 9047.3 9288.8 2619.7 2638.6 2649.9 2787.9 2802.9 955.4 966.6 971.6 982.8 991.4 19127.2 19192.3 19319.7 19630.0 19780.9 1013.7 1034.1 1036.6 1039.1 1041.2 10892.0 10919.2 10931.0 10953.3 11095.9 436440.0 442734.0 456636.0 464373.0 466443.0 107029.0 107828.5 108763.0 108960.0 109287.5 1165.6 1180.8 1184.5 1193.5 1202.5 17274.5 17550.3 17640.0 17760.6 17867.6 54293.7 55086.7 55869.4 57272.1 57571.0 69736.3 70530.6 70765.2 70862.0 71742.9 14156.3 14184.2 14284.0 14403.5 14499.4 22732.8 22856.9 22961.7 23106.0 23123.7 17225.0 18481.6 18724.6 18725.0 18744.9 16377.7 16657.8 17017.1 17072.1 17132.7 17935.3 18285.9 18313.4 18315.5 18463.8 13957.3 13976.5 14146.6 14159.2 14166.7 18024.9 18071.9 18312.0 18324.7 18402.5 11:46:35 INFO - PID 14055 | 11:46:35 INFO - PID 14055 | [#3] mootools.html Cycles:180 Average:9450.60 Median:2771.38 stddev:6837.27 (246.7%) stddev-sans-first:6829.37 11:46:35 INFO - PID 14055 | Values: 1351.6 1388.9 1403.9 1404.3 1406.1 14917.6 15862.9 15872.4 15892.8 15905.7 15279.8 15567.6 15603.1 15604.2 15606.8 2754.5 2796.2 2797.7 2799.7 2799.8 2674.8 2697.5 2732.7 2744.8 2748.3 2698.7 2714.3 2742.9 2783.8 2792.7 2739.7 2770.4 2770.5 2772.3 2772.5 2632.6 2666.6 2666.7 2676.7 2680.6 14647.8 14948.4 14954.0 14986.9 14999.8 7902.7 8179.4 8183.8 8204.5 8205.7 5350.8 5550.3 5562.4 5728.2 5745.5 1843.5 1922.2 2222.7 2286.9 2292.2 27252.0 27831.6 27831.9 27879.6 27904.4 7070.6 7087.5 7171.7 7297.6 7303.2 19718.9 19724.8 19725.9 19729.9 19740.6 8183.3 8302.5 8319.9 8322.0 8336.3 7306.7 7313.5 7344.5 7429.7 7437.3 5560.9 5667.0 5727.4 5752.7 5787.7 1739.9 1791.2 1802.1 1835.6 1841.5 813.7 832.4 848.4 851.4 866.5 7172.9 7188.5 7223.1 7241.6 7261.9 701.9 706.6 708.4 712.9 715.4 5095.3 5776.9 5785.6 5887.6 6028.2 21606.9 21811.5 22234.2 22264.0 22325.7 19897.4 21289.4 21299.6 21562.4 21694.1 9426.0 9708.9 10870.5 11012.8 11193.1 8838.5 9045.2 9109.6 9113.6 9305.9 18438.3 18671.5 18727.3 19011.4 19315.0 18253.5 18723.3 19191.8 19291.7 19481.2 11368.1 11769.1 11770.8 11777.6 11804.1 13819.8 13878.9 14009.9 14044.0 14058.1 9503.0 9520.2 9555.7 9644.4 9694.7 10801.3 10864.0 10876.6 11080.4 11146.2 9357.7 9471.5 9481.1 9481.2 9494.3 7151.4 7178.3 7254.7 7258.5 7348.9 7386.9 7396.6 7426.5 7462.9 7552.8 11:46:35 INFO - PID 14055 | 11:46:35 INFO - PID 14055 | [#4] prototype.html Cycles:180 Average:5526.27 Median:6187.27 stddev:1558.79 (25.2%) stddev-sans-first:1516.92 11:46:35 INFO - PID 14055 | Values: 505.2 506.1 529.4 534.3 539.2 6269.5 6285.5 6339.8 6342.7 6355.3 5924.2 5981.4 6162.2 6195.3 6197.1 5006.6 5037.3 5042.5 5045.0 5070.3 4820.6 5007.7 5024.1 5039.8 5051.7 4983.6 4987.9 4989.4 5037.5 5051.9 4922.9 4932.7 4942.9 5003.4 5032.9 4443.7 4478.3 4485.3 4490.1 4492.6 6109.4 6170.2 6179.2 6204.9 6225.1 5675.3 5683.2 5715.7 5717.7 5729.0 6452.4 6502.9 6539.4 6585.9 6588.3 4815.2 4845.3 4858.3 4862.4 4889.1 6881.0 6902.4 6915.0 6940.2 6987.7 4915.1 4940.8 4958.1 4962.5 4983.1 4997.0 5019.4 5058.5 5060.1 5080.3 5673.5 5691.8 5714.3 5749.5 5755.2 6512.5 6516.2 6530.8 6587.4 6634.8 1664.6 1714.6 1719.7 1726.4 1729.2 1200.3 1202.7 1219.8 1221.0 1239.0 5762.3 5772.2 5803.5 5808.1 5815.9 6754.6 6779.5 6783.5 6796.4 6801.3 5660.9 5780.2 5803.1 5839.4 5852.8 3561.9 3569.6 3581.1 3586.0 3595.2 6511.4 6517.5 6523.8 6530.5 6588.7 6827.0 6928.2 6953.1 6975.9 6988.1 6525.5 6542.8 6548.8 6568.9 6584.6 6780.3 6806.3 6808.0 6822.6 6829.4 6548.7 6737.2 6737.4 6752.8 6796.6 6488.2 6562.9 6566.2 6625.8 6688.6 4952.7 4960.5 4975.2 5008.2 5010.5 6760.3 6770.9 6859.3 6870.9 6891.9 6421.9 6446.3 6516.8 6541.2 6575.9 6475.2 6512.5 6536.8 6546.3 6585.3 6399.1 6430.3 6461.5 6507.4 6513.3 6364.2 6401.7 6423.7 6447.0 6639.6 6227.4 6262.2 6277.5 6309.4 6431.6 11:46:35 INFO - PID 14055 | 11:46:35 INFO - PID 14055 | [#5] yui.html Cycles:180 Average:23505.37 Median:20182.09 stddev:31959.65 (158.4%) stddev-sans-first:32004.17 11:46:35 INFO - PID 14055 | Values: 884.1 890.5 901.1 905.4 912.6 16823.5 17190.7 17204.5 17255.5 20633.3 18804.8 19762.5 19813.4 19833.6 19899.3 922.9 974.4 987.1 989.6 989.7 1007.0 1007.3 1007.6 1007.6 1010.8 987.3 1010.2 1013.2 1015.6 1069.2 990.7 991.4 991.8 992.9 994.9 732.4 736.6 736.7 737.4 738.0 19429.9 19796.7 20159.4 20164.2 20497.5 21178.0 21384.3 21655.0 21657.5 21900.6 17119.7 17361.4 17385.3 17528.7 17603.6 3750.0 3790.4 3791.8 3796.9 3801.7 128936.0 128977.4 128997.0 133190.3 133453.0 15627.8 16102.8 16172.4 16273.0 16611.9 30338.0 30703.4 30772.4 31587.4 31688.8 6592.9 6881.6 8147.3 8257.0 8258.8 4326.1 4409.1 4437.2 4437.2 4439.5 5238.4 5317.4 5347.5 5362.1 5669.4 1092.8 1111.5 1129.6 1129.7 1131.4 687.1 698.9 706.4 707.8 711.6 11759.5 11793.8 11931.1 12060.9 13208.1 695.5 696.0 700.2 701.6 708.9 5268.3 5445.8 5466.5 5519.4 5813.8 100741.0 104788.5 106974.0 107718.0 108133.5 91250.5 93988.5 94782.0 96234.5 96698.0 81523.0 82709.0 83462.6 84808.6 87343.6 10472.3 10536.6 10642.6 10859.2 10902.7 41994.0 42629.0 43209.6 43403.9 44531.1 52518.5 71395.6 71879.0 72479.3 74722.0 18144.9 18409.4 18668.9 18668.9 18693.6 15630.4 16114.1 16143.6 18587.3 20200.0 18400.4 18488.8 18768.3 18803.5 18805.4 18280.7 18507.8 18628.0 18702.0 18775.3 16303.0 17115.6 18285.7 18843.9 19000.2 11890.6 12330.6 12725.7 12769.9 12857.8 14505.3 14578.8 14741.0 14798.9 14928.5 11:46:35 INFO - PID 14055 | -------- Summary: end -------- 11:46:35 INFO - PID 14055 | 11:46:36 INFO - TEST-INFO | 14055: exit 0 11:46:36 INFO - TEST-OK | dromaeo_css | took 1110959ms 11:46:36 INFO - TEST-START | kraken 11:46:36 INFO - Initialising browser for kraken test... 11:46:36 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54507/getInfo.html -profile /tmp/tmpkx2TPt/profile 11:46:36 INFO - TEST-INFO | started process 14425 (/builds/slave/test/build/application/firefox/firefox http://localhost:54507/getInfo.html) 11:46:41 INFO - TEST-INFO | 14425: exit 0 11:46:41 INFO - Browser initialized. 11:46:41 INFO - Running cycle 1/1 for kraken test... 11:46:41 INFO - Using env: {'DISPLAY': ':0', 11:46:41 INFO - 'HOME': '/home/cltbld', 11:46:41 INFO - 'JSGC_DISABLE_POISONING': '1', 11:46:41 INFO - 'LANG': 'en_US.UTF-8', 11:46:41 INFO - 'LANGUAGE': 'en_US:en', 11:46:41 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:46:41 INFO - 'LOGNAME': 'cltbld', 11:46:41 INFO - 'MAIL': '/var/mail/cltbld', 11:46:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:46:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:46:41 INFO - 'MOZ_CRASHREPORTER': '1', 11:46:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:41 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:46:41 INFO - 'MOZ_NO_REMOTE': '1', 11:46:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:46:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:46:41 INFO - 'NO_EM_RESTART': '1', 11:46:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:46:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:41 INFO - 'PWD': '/builds/slave/test', 11:46:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:46:41 INFO - 'RUST_BACKTRACE': 'full', 11:46:41 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:46:41 INFO - 'SHELL': '/bin/bash', 11:46:41 INFO - 'SHLVL': '1', 11:46:41 INFO - 'TERM': 'linux', 11:46:41 INFO - 'TMOUT': '86400', 11:46:41 INFO - 'USER': 'cltbld', 11:46:41 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810', 11:46:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:41 INFO - '_': '/tools/buildbot/bin/python'} 11:46:41 INFO - TEST-INFO | started process 14608 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkx2TPt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:46:42 INFO - PID 14608 | 11:46:42 INFO - PID 14608 | (/builds/slave/test/build/application/firefox/firefox:14659): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:46:42 INFO - PID 14608 | 11:46:53 INFO - PID 14608 | RSS: Main: 170463232 11:46:53 INFO - PID 14608 | 11:46:55 INFO - PID 14608 | Cycle 1(1): loaded http://localhost:54507/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54507/tests/kraken/driver.html?testName=audio-beat-detection) 11:46:56 INFO - PID 14608 | RSS: Main: 175259648 11:46:56 INFO - PID 14608 | 11:46:59 INFO - PID 14608 | Cycle 1(1): loaded http://localhost:54507/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54507/tests/kraken/driver.html?testName=audio-dft) 11:46:59 INFO - PID 14608 | RSS: Main: 175550464 11:46:59 INFO - PID 14608 | 11:47:02 INFO - PID 14608 | Cycle 1(1): loaded http://localhost:54507/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54507/tests/kraken/driver.html?testName=audio-fft) 11:47:03 INFO - PID 14608 | RSS: Main: 169971712 11:47:03 INFO - PID 14608 | 11:47:05 INFO - PID 14608 | Cycle 1(1): loaded http://localhost:54507/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54507/tests/kraken/driver.html?testName=audio-oscillator) 11:47:06 INFO - PID 14608 | RSS: Main: 170475520 11:47:06 INFO - PID 14608 | 11:47:08 INFO - PID 14608 | Cycle 1(1): loaded http://localhost:54507/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54507/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:47:08 INFO - PID 14608 | RSS: Main: 170688512 11:47:08 INFO - PID 14608 | 11:47:12 INFO - PID 14608 | Cycle 1(1): loaded http://localhost:54507/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54507/tests/kraken/driver.html?testName=imaging-darkroom) 11:47:12 INFO - PID 14608 | RSS: Main: 173309952 11:47:12 INFO - PID 14608 | 11:47:16 INFO - PID 14608 | Cycle 1(1): loaded http://localhost:54507/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54507/tests/kraken/driver.html?testName=imaging-desaturate) 11:47:16 INFO - PID 14608 | RSS: Main: 173559808 11:47:16 INFO - PID 14608 | 11:47:19 INFO - PID 14608 | Cycle 1(1): loaded http://localhost:54507/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54507/tests/kraken/driver.html?testName=json-parse-financial) 11:47:20 INFO - PID 14608 | RSS: Main: 173588480 11:47:20 INFO - PID 14608 | 11:47:21 INFO - PID 14608 | Cycle 1(1): loaded http://localhost:54507/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54507/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:47:22 INFO - PID 14608 | RSS: Main: 173760512 11:47:22 INFO - PID 14608 | 11:47:24 INFO - PID 14608 | Cycle 1(1): loaded http://localhost:54507/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54507/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:47:24 INFO - PID 14608 | RSS: Main: 173637632 11:47:24 INFO - PID 14608 | 11:47:26 INFO - PID 14608 | Cycle 1(1): loaded http://localhost:54507/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54507/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:47:27 INFO - PID 14608 | RSS: Main: 170713088 11:47:27 INFO - PID 14608 | 11:47:30 INFO - PID 14608 | Cycle 1(1): loaded http://localhost:54507/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54507/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:47:30 INFO - PID 14608 | RSS: Main: 170745856 11:47:30 INFO - PID 14608 | 11:47:33 INFO - PID 14608 | Cycle 1(1): loaded http://localhost:54507/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54507/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:47:34 INFO - PID 14608 | RSS: Main: 171728896 11:47:34 INFO - PID 14608 | 11:47:36 INFO - PID 14608 | Cycle 1(1): loaded http://localhost:54507/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54507/tests/kraken/driver.html?testName=ai-astar) 11:47:36 INFO - PID 14608 | RSS: Main: 172593152 11:47:36 INFO - PID 14608 | 11:47:36 INFO - PID 14608 | __start_tp_report 11:47:36 INFO - PID 14608 | _x_x_mozilla_page_load 11:47:36 INFO - PID 14608 | _x_x_mozilla_page_load_details 11:47:36 INFO - PID 14608 | |i|pagename|runs| 11:47:36 INFO - PID 14608 | |0;ai-astar;97;108;100;106;106;108;111;97;109;110 11:47:36 INFO - PID 14608 | |1;audio-beat-detection;143;118;155;185;118;141;151;114;170;116 11:47:36 INFO - PID 14608 | |2;audio-dft;163;163;163;160;161;161;161;164;172;166 11:47:36 INFO - PID 14608 | |3;audio-fft;74;74;77;163;74;74;73;95;95;75 11:47:36 INFO - PID 14608 | |4;audio-oscillator;79;79;133;79;78;80;131;79;79;133 11:47:36 INFO - PID 14608 | |5;imaging-gaussian-blur;114;114;115;115;117;114;114;114;114;116 11:47:36 INFO - PID 14608 | |6;imaging-darkroom;150;148;148;147;148;147;149;148;157;148 11:47:36 INFO - PID 14608 | |7;imaging-desaturate;94;94;94;94;93;94;94;93;94;93 11:47:36 INFO - PID 14608 | |8;json-parse-financial;53;55;51;53;52;53;53;52;53;55 11:47:36 INFO - PID 14608 | |9;json-stringify-tinderbox;38;37;37;37;37;37;38;38;37;38 11:47:36 INFO - PID 14608 | |10;stanford-crypto-aes;79;76;73;74;75;76;75;74;74;73 11:47:36 INFO - PID 14608 | |11;stanford-crypto-ccm;129;120;118;121;117;122;114;125;128;161 11:47:36 INFO - PID 14608 | |12;stanford-crypto-pbkdf2;177;177;169;167;171;168;166;167;176;167 11:47:36 INFO - PID 14608 | |13;stanford-crypto-sha256-iterative;65;66;64;63;69;63;65;62;65;62 11:47:36 INFO - PID 14608 | __end_tp_report 11:47:36 INFO - PID 14608 | __start_cc_report 11:47:36 INFO - PID 14608 | _x_x_mozilla_cycle_collect,948 11:47:36 INFO - PID 14608 | __end_cc_report 11:47:36 INFO - PID 14608 | __startTimestamp1504205256395__endTimestamp 11:47:36 INFO - PID 14608 | 11:47:36 INFO - PID 14608 | ------- Summary: start ------- 11:47:36 INFO - PID 14608 | Number of tests: 14 11:47:36 INFO - PID 14608 | 11:47:36 INFO - PID 14608 | [#0] ai-astar Cycles:10 Average:105.20 Median:109.50 stddev:5.27 (4.8%) stddev-sans-first:4.68 11:47:36 INFO - PID 14608 | Values: 97.0 108.0 100.0 106.0 106.0 108.0 111.0 97.0 109.0 110.0 11:47:36 INFO - PID 14608 | 11:47:36 INFO - PID 14608 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:147.00 stddev:24.70 (16.8%) stddev-sans-first:26.18 11:47:36 INFO - PID 14608 | Values: 143.0 118.0 155.0 185.0 118.0 141.0 151.0 114.0 170.0 116.0 11:47:36 INFO - PID 14608 | 11:47:36 INFO - PID 14608 | [#2] audio-dft Cycles:10 Average:163.40 Median:163.00 stddev:3.50 (2.1%) stddev-sans-first:3.71 11:47:36 INFO - PID 14608 | Values: 163.0 163.0 163.0 160.0 161.0 161.0 161.0 164.0 172.0 166.0 11:47:36 INFO - PID 14608 | 11:47:36 INFO - PID 14608 | [#3] audio-fft Cycles:10 Average:87.40 Median:74.50 stddev:27.93 (37.5%) stddev-sans-first:29.20 11:47:36 INFO - PID 14608 | Values: 74.0 74.0 77.0 163.0 74.0 74.0 73.0 95.0 95.0 75.0 11:47:36 INFO - PID 14608 | 11:47:36 INFO - PID 14608 | [#4] audio-oscillator Cycles:10 Average:95.00 Median:79.00 stddev:25.77 (32.6%) stddev-sans-first:26.68 11:47:36 INFO - PID 14608 | Values: 79.0 79.0 133.0 79.0 78.0 80.0 131.0 79.0 79.0 133.0 11:47:36 INFO - PID 14608 | 11:47:36 INFO - PID 14608 | [#5] imaging-gaussian-blur Cycles:10 Average:114.70 Median:114.50 stddev:1.06 (0.9%) stddev-sans-first:1.09 11:47:36 INFO - PID 14608 | Values: 114.0 114.0 115.0 115.0 117.0 114.0 114.0 114.0 114.0 116.0 11:47:36 INFO - PID 14608 | 11:47:36 INFO - PID 14608 | [#6] imaging-darkroom Cycles:10 Average:149.00 Median:148.00 stddev:2.94 (2.0%) stddev-sans-first:3.10 11:47:36 INFO - PID 14608 | Values: 150.0 148.0 148.0 147.0 148.0 147.0 149.0 148.0 157.0 148.0 11:47:36 INFO - PID 14608 | 11:47:36 INFO - PID 14608 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 11:47:36 INFO - PID 14608 | Values: 94.0 94.0 94.0 94.0 93.0 94.0 94.0 93.0 94.0 93.0 11:47:36 INFO - PID 14608 | 11:47:36 INFO - PID 14608 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:53.00 stddev:1.25 (2.4%) stddev-sans-first:1.32 11:47:36 INFO - PID 14608 | Values: 53.0 55.0 51.0 53.0 52.0 53.0 53.0 52.0 53.0 55.0 11:47:36 INFO - PID 14608 | 11:47:36 INFO - PID 14608 | [#9] json-stringify-tinderbox Cycles:10 Average:37.40 Median:37.50 stddev:0.52 (1.4%) stddev-sans-first:0.50 11:47:36 INFO - PID 14608 | Values: 38.0 37.0 37.0 37.0 37.0 37.0 38.0 38.0 37.0 38.0 11:47:36 INFO - PID 14608 | 11:47:36 INFO - PID 14608 | [#10] stanford-crypto-aes Cycles:10 Average:74.90 Median:75.00 stddev:1.79 (2.4%) stddev-sans-first:1.13 11:47:36 INFO - PID 14608 | Values: 79.0 76.0 73.0 74.0 75.0 76.0 75.0 74.0 74.0 73.0 11:47:36 INFO - PID 14608 | 11:47:36 INFO - PID 14608 | [#11] stanford-crypto-ccm Cycles:10 Average:125.50 Median:123.50 stddev:13.34 (10.8%) stddev-sans-first:14.09 11:47:36 INFO - PID 14608 | Values: 129.0 120.0 118.0 121.0 117.0 122.0 114.0 125.0 128.0 161.0 11:47:36 INFO - PID 14608 | 11:47:36 INFO - PID 14608 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.50 Median:170.00 stddev:4.48 (2.6%) stddev-sans-first:4.09 11:47:36 INFO - PID 14608 | Values: 177.0 177.0 169.0 167.0 171.0 168.0 166.0 167.0 176.0 167.0 11:47:36 INFO - PID 14608 | 11:47:36 INFO - PID 14608 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:65.00 stddev:2.12 (3.3%) stddev-sans-first:2.24 11:47:36 INFO - PID 14608 | Values: 65.0 66.0 64.0 63.0 69.0 63.0 65.0 62.0 65.0 62.0 11:47:36 INFO - PID 14608 | -------- Summary: end -------- 11:47:36 INFO - PID 14608 | 11:47:36 INFO - PID 14608 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:47:37 INFO - TEST-INFO | 14608: exit 0 11:47:37 INFO - TEST-OK | kraken | took 60798ms 11:47:37 INFO - SUITE-END | took 1171s 11:47:37 INFO - Completed test suite (00:19:32) 11:47:37 INFO - javascript benchmark 11:47:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8567.856583741996, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3308.8511017004234, 3312.403767321882, 3317.933641327173, 3344.8996530104105, 3361.3613413451426, 6357.459615932639, 6358.491320694345, 6379.393957574029, 6409.551331406803, 6410.038494225862, 6244.812655620327, 6320.146780184674, 6332.651704156269, 6333.113364129025, 6342.556021078526, 5773.884522309561, 5777.1623114648355, 5779.422057794208, 5791.7972870949525, 5848.941510584904, 5837.678927658977, 5876.823695289127, 5914.378990206031, 5929.991901376755, 5933.199018132562, 5794.130880367937, 5864.091065884794, 5876.676782776945, 5888.146218798272, 5950.5834591578605, 5643.492085712305, 5769.105788602731, 5876.55925805562, 5924.644521328734, 5925.318588362329, 5528.253685752412, 5772.163036359704, 5831.0, 5834.5624078194305, 5859.4140585941495, 6224.190855188842, 6321.87356252877, 6367.713452894631, 6370.1718776558755, 6412.743490260431, 6519.380658837403, 6617.073609694647, 6743.561668491532, 6788.524803263774, 6832.214295355998, 6845.14435695538, 6851.794446166623, 6870.725170993154, 6891.241963384024, 6898.517103802736, 6328.208973878265, 6411.711472983727, 6460.806175814686, 6465.418112369909, 6466.0, 6959.930400696029, 7017.859642807115, 7035.718571257093, 7058.364747172779, 7123.572585644878, 6378.649174295366, 6608.504362172856, 6675.699593518206, 6715.2613212546585, 6813.863722725518, 6801.795946121624, 6849.863002740017, 6886.586804791628, 6886.965565172142, 6922.342377474132, 6803.65981700913, 6872.553284036521, 6879.759208427681, 6885.0, 6887.242403335629, 6793.980902864511, 6800.625965571941, 6809.353111454404, 6904.067950826574, 6920.5847649141215, 5242.318498595158, 5304.151335786333, 5314.202869569518, 5316.468353164652, 5322.33470816148, 4633.7683115844075, 4671.649626278043, 4706.717596944194, 4712.88217794551, 4770.165221086296, 5663.122216056518, 5693.658380497183, 5781.161731548902, 5788.507976821933, 5791.102379131242, 6620.5365624406795, 6631.966840165865, 6635.203775546869, 6640.335966403321, 6641.568298060611, 5768.105943578752, 5776.537876969833, 5778.682172480554, 5783.450572195635, 5789.102689083199, 5692.430756924275, 5721.485066344049, 5820.417958204146, 5877.38287479817, 5905.911411328833, 6644.700988455531, 6694.832629184212, 6708.463322934153, 6708.832279192961, 6740.29226931175, 6817.284185160586, 6830.0, 6842.349976752201, 6912.308769123148, 6928.237893831573, 6449.677516124081, 6551.148350714377, 6610.305917878553, 6674.365935236145, 6702.497312701665, 6631.602103873783, 6709.094272273376, 6729.2934241903895, 6733.528652994243, 6754.459643228432, 6792.490563207878, 6794.116764820542, 6886.827829304107, 6888.448924086161, 6893.414059804973, 6493.8701225976165, 6545.280019197789, 6580.5722628029025, 6635.23694775107, 6644.966775166094, 6421.229452465734, 6425.45383642377, 6484.935150648622, 6499.0, 6567.211934567882, 6471.0, 6489.253735820253, 6547.345265473605, 6600.702968366531, 6648.069270302065, 6629.668516574248, 6686.465082793462, 6740.292269311652, 6763.3236676333945, 6776.457883369417, 6153.507719382349, 6253.343398943045, 6390.968045159744, 6441.194850643669, 6542.280349161493], "value": 6192.9519885995405, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [817.7424111404907, 865.1348651348651, 881.4711173296023, 882.2324577617474, 1125.4766533561892, 30421.543676844864, 40830.79584602077, 42190.15619687608, 42201.36697949533, 42240.0, 42265.57734422662, 42580.14839703204, 42593.574064259345, 42632.14735705284, 42699.359509607384, 7815.374770018385, 7825.491343062697, 7950.1254861965135, 7952.324052455549, 7954.761357159295, 7812.6093695315, 7879.290863822255, 7888.881666775003, 7895.881561776578, 7914.762557123267, 7885.29032387085, 7937.44437889348, 7938.523688578675, 7940.761777146695, 7942.126366099725, 7874.803129921741, 7879.251471110293, 7881.172476889901, 7893.013373328363, 7897.526148431112, 7764.767056988299, 7798.37612990965, 7812.1016083150025, 7818.14000459949, 7819.530828150329, 38528.22943541114, 38664.226715465535, 38724.80637596794, 38764.80617596894, 38883.8055809722, 3539.2213712983103, 3571.8214089295693, 3577.7853328800356, 3579.7494175407733, 3582.6238244984424, 31618.841905790323, 31645.84177079123, 31701.365972680647, 31729.206769830707, 31750.523742143887, 5081.872953176163, 5219.765110569995, 5226.5818734501145, 5249.632525723201, 5254.106801843695, 192379.0, 194436.0, 195358.02320988447, 195544.0, 195789.0, 48301.75849120732, 48487.75756121197, 48572.7571362148, 48713.512864871605, 48713.75643121762, 50614.493855061715, 50749.0, 50772.74613626908, 50826.0, 50867.491325087016, 12938.805917911239, 15221.847781522265, 15471.845281547265, 15479.458218962282, 15490.07059576429, 10447.373157610567, 12187.817182742267, 12320.81518777219, 12321.075919306088, 12406.689832754253, 14685.19231442281, 14719.779203311959, 14740.894432917574, 14772.113673179643, 14798.260086995822, 5751.827445176651, 5886.911696324559, 5890.4698577128265, 5935.554833387513, 6059.848503787441, 998.9850152247722, 1012.6455740490769, 1013.7212266626736, 1024.4877561219391, 1025.9846102308472, 22821.087156513884, 28338.858305708338, 28346.43307133846, 28777.561121943818, 28949.565756513668, 1074.511097450658, 1091.770728147098, 1096.3586302013252, 1097.9725506862392, 1113.615802548136, 12518.06114541413, 12668.936655316664, 12683.936580317039, 12758.744825103446, 12900.35498225085, 226866.0, 229894.0, 229912.0, 230289.0, 231129.0, 52444.0, 53214.46785532172, 53223.467765322624, 53240.46759532433, 53607.0, 1424.4088703188268, 1442.6753980354335, 1447.8407375188722, 1451.7241724072392, 1464.7143806957754, 22806.31581052571, 22808.0, 22913.770862291163, 22956.540869182856, 23184.53630927406, 35426.0, 35582.644173557936, 35608.643913561566, 35649.46525802063, 35725.46411803773, 203270.0, 203509.0, 204277.0, 204443.0, 204545.0, 34581.48127778136, 36840.63159368372, 37678.0, 37830.62169378271, 37855.43216851805, 45965.0, 46135.53864461447, 46172.538274616825, 46519.06961860812, 46726.0, 33670.32659346848, 33905.49141762925, 34156.65843341634, 34256.486152708225, 34613.65386346205, 33312.50031249582, 33314.66685333115, 33571.83214083914, 33601.83199083989, 34173.82913085419, 37388.25223495569, 37467.2506549862, 37621.81189094037, 37714.43428348632, 37939.620603793606, 32858.17854553559, 33284.33431331311, 33504.497432538046, 33514.497282540295, 34049.0, 24170.637440438055, 24208.63687044731, 24305.39236519101, 24654.01383944694, 25008.249752507454], "value": 17488.154769057066, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.6684323092607, 573.790566443248, 574.4227051812927, 592.2715060475616, 594.8156071617798, 22378.10487580497, 22690.0, 23263.0, 23265.651015234766, 23286.18498352556, 24949.0, 25384.87307563464, 25488.87255563724, 25528.872355638243, 25555.87222063887, 6512.934870651304, 6662.666866656672, 6674.56615320005, 6678.232003319632, 6678.833029174261, 6013.879722405549, 6477.773277935274, 6507.934920650804, 6560.835979100537, 6572.967135164317, 6450.096986421881, 6457.547971641963, 6507.3492650735025, 6516.283208847023, 6527.281998980108, 6440.516961227903, 6446.194225721785, 6485.059666348377, 6497.545171837974, 6503.382178693017, 6044.123602077718, 6046.727897244635, 6049.425304596057, 6075.605085669417, 6077.20996270482, 28825.27936801576, 29374.265643358875, 29887.252818679706, 30025.249368765737, 30073.097807065824, 1948.133080779054, 1966.4887129346473, 1980.3959792263354, 1981.0292956451378, 1982.0189006441904, 19381.51546211356, 19908.90045549763, 20012.899935500376, 20015.299464518674, 20085.39743807688, 2376.6553849691873, 2435.7942818304873, 2489.6763420755187, 2493.4265119022543, 2504.8998040078377, 132999.0, 138678.99999999898, 138771.0, 139727.0, 139814.30092849574, 14244.290685117852, 14343.641408964651, 14450.855491445162, 14633.512302908532, 15167.393304267705, 22910.541789164123, 23043.308700739006, 23086.884565577402, 23091.422714432272, 23093.88453057724, 9713.951430242803, 9868.851967220497, 9951.303408761318, 9998.80002399948, 10116.241281903887, 7672.616369181518, 9200.90799092014, 9240.3531752778, 9268.675596354095, 9308.44149351041, 8010.5994700264755, 8998.325125615605, 9028.954855225813, 9047.276217902552, 9288.767780805534, 2619.6594442722703, 2638.61740047692, 2649.9337516561854, 2787.9303017424727, 2802.943941121207, 955.4458414119794, 966.6278482784179, 971.6307803034942, 982.8083523712951, 991.3952488981715, 19127.2349106037, 19192.328268510537, 19319.710204346946, 19630.0, 19780.90109549443, 1013.6604237580493, 1034.131329683055, 1036.6009086501601, 1039.1063685230695, 1041.245097304448, 10892.0, 10919.235653504338, 10931.0, 10953.34279943206, 11095.945885140898, 436440.0, 442734.0, 456636.0, 464373.0, 466443.0, 107029.0, 107828.46085769522, 108763.0, 108960.0, 109287.45356273168, 1165.6328256599145, 1180.8110702287593, 1184.5261895241972, 1193.4569770754285, 1202.5370232460566, 17274.481765547054, 17550.297988080332, 17640.0, 17760.644787103927, 17867.553311166805, 54293.72853135709, 55086.72456637691, 55869.44130558642, 57272.14091788543, 57571.0, 69736.30263697298, 70530.64734676294, 70765.23086922662, 70862.0, 71742.92385614116, 14156.292185390896, 14184.219867907364, 14284.0, 14403.495877644233, 14499.420023198954, 22732.772672273066, 22856.885715571316, 22961.655575166053, 23106.0, 23123.65314520317, 17225.0, 18481.630367392307, 18724.6255074895, 18724.970126643166, 18744.906275468536, 16377.672446550763, 16657.83342166563, 17017.149142543072, 17072.146392680068, 17132.657346852742, 17935.282588696828, 18285.908570457064, 18313.3590324338, 18315.54211144732, 18463.79985300951, 13957.302134893418, 13976.510822121178, 14146.575602731935, 14159.221242831743, 14166.716665666836, 18024.90987545054, 18071.909640451715, 18312.0, 18324.725129122806, 18402.539936501693], "value": 11836.297784936105, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1351.5539871842293, 1388.881945034672, 1403.8596140385957, 1404.3329418526207, 1406.0860440713532, 14917.552473425802, 15862.920685396586, 15872.365105395786, 15892.84107158928, 15905.681886362267, 15279.770803437958, 15567.610809729762, 15603.141827199499, 15604.219789010533, 15606.765898511503, 2754.5317296059716, 2796.2310364649784, 2797.692253852075, 2799.650043744532, 2799.8040137190405, 2674.7993900457445, 2697.4874773793013, 2732.699403065661, 2744.821586596875, 2748.2854457840904, 2698.743619356168, 2714.267147870074, 2742.862856857159, 2783.832970021795, 2792.706765789593, 2739.671239451269, 2770.362816552194, 2770.459760346733, 2772.306923269183, 2772.473230086277, 2632.578787394027, 2666.6000099984963, 2666.7199944005793, 2676.745709157627, 2680.584509401046, 14647.780283295759, 14948.40206391743, 14954.0, 14986.925065374713, 14999.775003374958, 7902.723404680866, 8179.4274400791965, 8183.754487365389, 8204.548749818758, 8205.671773129068, 5350.8394748157225, 5550.27846379968, 5562.360328562227, 5728.169415434739, 5745.454181852718, 1843.511469460594, 1922.1734654098732, 2222.655488399301, 2286.8970896309706, 2292.2321022457495, 27252.0, 27831.582526262122, 27831.86084069587, 27879.581806273123, 27904.441911161663, 7070.575765454089, 7087.539309944837, 7171.748988785367, 7297.635118244067, 7303.160136584254, 19718.90140549288, 19724.802751972584, 19725.901370493055, 19729.90135049344, 19740.605187896163, 8183.263506284463, 8302.460340077876, 8319.875201871977, 8322.042965059096, 8336.333093352518, 7306.744263950736, 7313.524620899624, 7344.522606030698, 7429.739959101514, 7437.256274372519, 5560.888782224414, 5666.9716651416475, 5727.398623144593, 5752.683602401824, 5787.739551720183, 1739.9304027838998, 1791.1939627167826, 1802.0809387212616, 1835.5686413692772, 1841.5027942455463, 813.7436707437257, 832.3965125284242, 848.3510114762212, 851.3784936996028, 866.4584634603373, 7172.892406613905, 7188.4968052235845, 7223.097112860893, 7241.637918104074, 7261.89107163393, 701.8876979683226, 706.5548704316247, 708.4190963409955, 712.9180144283474, 715.3526058916689, 5095.31213286209, 5776.913346299809, 5785.623934444247, 5887.646741195542, 6028.186194863637, 21606.891965540388, 21811.454713631967, 22234.221802236843, 22264.0, 22325.665115023286, 19897.403077907686, 21289.36131916045, 21299.574008519743, 21562.353129406143, 21694.13223471075, 9425.952870235742, 9708.90291097094, 10870.456477176267, 11012.779744405067, 11193.10455163585, 8838.513881736566, 9045.185933265775, 9109.635614575343, 9113.589888455168, 9305.860412093689, 18438.26246950107, 18671.533211669816, 18727.344542940933, 19011.429657110308, 19315.0, 18253.54366140804, 18723.25106995705, 19191.808081919004, 19291.710624340925, 19481.220751169796, 11368.090552755595, 11769.058475322125, 11770.76458470843, 11777.587784427504, 11804.114691398352, 13819.792703109662, 13878.930605347312, 14009.859901400856, 14044.0, 14058.086224395382, 9502.952485237529, 9520.190783783455, 9555.665551705657, 9644.421334719938, 9694.709158725249, 10801.297915635458, 10864.0, 10876.619318323821, 11080.390578517936, 11146.164037697043, 9357.672481463116, 9471.47906865129, 9481.146696797045, 9481.241500679791, 9494.335396522178, 7151.356377926011, 7178.318059784521, 7254.746083887038, 7258.49190556656, 7348.853022939405, 7386.891966205112, 7396.593187374746, 7426.517276377018, 7462.946817785528, 7552.773416797505], "value": 6725.5121514672755, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.19169329073486, 506.1168261383888, 529.4387948774297, 534.2733881920587, 539.2046731071667, 6269.49844012479, 6285.522902118004, 6339.778107766229, 6342.682865856712, 6355.332690067546, 5924.170616113747, 5981.4018598140165, 6162.22972128484, 6195.349488303719, 6197.132401463788, 5006.649534532584, 5037.294778730962, 5042.495750424947, 5044.974775126128, 5070.29015937769, 4820.614350851941, 5007.724575148365, 5024.120778863702, 5039.798408063692, 5051.747412629372, 4983.551480366767, 4987.875303117433, 4989.401271847383, 5037.49625037497, 5051.924221136685, 4922.90154196914, 4932.704037757746, 4942.851714448572, 5003.374578177728, 5032.949670503303, 4443.688941774077, 4478.305862591271, 4485.304777759453, 4490.079533695584, 4492.595666390041, 6109.389061093855, 6170.167027451326, 6179.196704428396, 6204.937950620481, 6225.066240063981, 5675.269042941901, 5683.175939488751, 5715.685637289947, 5717.656940583578, 5729.0, 6452.354764523557, 6502.90245646311, 6539.411452969208, 6585.9012114818315, 6588.341165883421, 4815.229563269895, 4845.3216549683075, 4858.271259311096, 4862.3922009748785, 4889.1443997300685, 6880.965595172042, 6902.413294869942, 6914.9654251728925, 6940.236573976858, 6987.7204911803465, 4915.083117584145, 4940.802367905244, 4958.140697238536, 4962.503749625081, 4983.103041452501, 4997.0, 5019.42276638184, 5058.494150584986, 5060.089183946851, 5080.263361812516, 5673.546116310686, 5691.8292451226525, 5714.257146570919, 5749.453801888814, 5755.22304488897, 6512.479001679854, 6516.152900123048, 6530.8040758777315, 6587.440067594212, 6634.767783127568, 1664.617138058229, 1714.579927917668, 1719.7420386941808, 1726.3526177683368, 1729.2391347806924, 1200.255841378351, 1202.699325168708, 1219.7560487902279, 1220.993895030537, 1238.9876101238872, 5762.2509073820975, 5772.191893134965, 5803.506701930383, 5808.128780682931, 5815.912761308584, 6754.628495432798, 6779.5254332197255, 6783.491238157158, 6796.35434633709, 6801.251862295144, 5660.915086273709, 5780.1907732917425, 5803.129530570363, 5839.386864379264, 5852.795152169739, 3561.895812298196, 3569.5716514018486, 3581.0510214793103, 3586.0138461922656, 3595.1910820065277, 6511.413972742476, 6517.543771935919, 6523.804285871432, 6530.510211734139, 6588.736450541925, 6827.0, 6928.237893831674, 6953.130858642669, 6975.8604827903155, 6988.126484189476, 6525.477961763143, 6542.789583926989, 6548.8362790930605, 6568.86862262752, 6584.604923704497, 6780.32196780318, 6806.285340039422, 6808.0, 6822.556533825285, 6829.419499342612, 6548.672566371567, 6737.157855268091, 6737.3599508046655, 6752.797416077526, 6796.59220446755, 6488.221413430419, 6562.949928011495, 6566.2120545532935, 6625.801225963036, 6688.632125232964, 4952.678075925053, 4960.479149689375, 4975.179095449355, 5008.198688209869, 5010.549050585465, 6760.256371799, 6770.93229067703, 6859.314068593101, 6870.862582748217, 6891.862162756817, 6421.935780642134, 6446.258680251831, 6516.771912983023, 6541.215054193527, 6575.901361479678, 6475.158229430144, 6512.511561632802, 6536.8365790855605, 6546.279909209888, 6585.275619681735, 6399.136116624382, 6430.260520040255, 6461.450776684034, 6507.381798729113, 6513.316101809241, 6364.204474440695, 6401.743930242738, 6423.743050277936, 6447.0, 6639.634820084942, 6227.408396202353, 6262.2172228471445, 6277.529185311028, 6309.400606942333, 6431.614103153639], "value": 5067.2871320265795, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [884.1114679746853, 890.483519558656, 901.0898992018061, 905.4476769170809, 912.6440688131626, 16823.49529514115, 17190.74213886791, 17204.48386548404, 17255.482335529923, 20633.277835275767, 18804.811951880478, 19762.505937351536, 19813.40559783205, 19833.603327933433, 19899.30352437665, 922.9169374756287, 974.4348277998744, 987.1362557761959, 989.5547003848261, 989.7327721515187, 1006.9728876545919, 1007.3351587951953, 1007.591925270263, 1007.6372505897871, 1010.777628921636, 987.2644879564734, 1010.1817527802467, 1013.2046343620223, 1015.5836107196051, 1069.187417562654, 990.6829814459376, 991.4447909170851, 991.8413053911376, 992.8907820139779, 994.9204063674889, 732.3555271361221, 736.5654263984221, 736.6869080640706, 737.4284929179875, 737.9667914943841, 19429.9028504858, 19796.70304945427, 20159.39521814348, 20164.19343226269, 20497.4875628109, 21178.0, 21384.25155119579, 21655.0, 21657.45856353588, 21900.561988760135, 17119.74320385195, 17361.392351267772, 17385.304587816474, 17528.737068943978, 17603.559911002198, 3750.043738846604, 3790.4124860646643, 3791.848326066954, 3796.886093417202, 3801.6958643308467, 128936.0, 128977.35511322477, 128997.0, 133190.3340483301, 133453.0, 15627.765583516257, 16102.838971610134, 16172.353105875869, 16273.0, 16611.91694041522, 30338.0, 30703.385932281228, 30772.384552309275, 31587.36825263528, 31688.841555792536, 6592.901106483407, 6881.621510816853, 8147.348212143014, 8256.958715206505, 8258.752237432887, 4326.069894972596, 4409.0520538084465, 4437.201303765289, 4437.245668236407, 4439.467263928349, 5238.423773384925, 5317.441668624816, 5347.51872331492, 5362.1420572708175, 5669.376368599451, 1092.808758467265, 1111.4998250787178, 1129.5651174297955, 1129.740159763259, 1131.4003578103618, 687.1273482677072, 698.9266127056586, 706.4242642246553, 707.8053535277737, 711.5979471598633, 11759.47082381295, 11793.820617938138, 11931.1051671125, 12060.939695301468, 13208.141470804367, 695.45059403071, 695.952591350015, 700.1563116444672, 701.551007355293, 708.8972099045609, 5268.28878101459, 5445.754941027663, 5466.480684334982, 5519.365272993577, 5813.79651712188, 100741.0, 104788.47605761922, 106974.0, 107718.0, 108133.45933270283, 91250.54374728084, 93988.53005735064, 94782.0, 96234.51882740541, 96698.0, 81523.0, 82709.0, 83462.58268708618, 84808.57595711981, 87343.56328218318, 10472.319299245524, 10536.578536858746, 10642.574297028343, 10859.2398532102, 10902.672919812418, 41994.0, 42629.0, 43209.56790432181, 43403.9149021277, 44531.10937781291, 52518.474815252885, 71395.64302178664, 71879.0, 72479.27520724725, 74722.0, 18144.909275453538, 18409.355672551395, 18668.906655466635, 18668.906655466635, 18693.626127477648, 15630.374785008928, 16114.113723745306, 16143.596410089842, 18587.25650974, 20199.990000499623, 18400.35598754091, 18488.815111848708, 18768.343107991153, 18803.529911751768, 18805.435836924367, 18280.72578911344, 18507.814921851146, 18628.0, 18702.0, 18775.34286299973, 16303.0, 17115.572110697332, 18285.71999960043, 18843.90578047101, 19000.23999040023, 11890.643280701594, 12330.568430104904, 12725.745485090432, 12769.936150319189, 12857.807132893202, 14505.347259373142, 14578.781318280446, 14741.0, 14798.852011479747, 14928.477503287333], "value": 9054.175546994247, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1475.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 108.0, 100.0, 106.0, 106.0, 108.0, 111.0, 97.0, 109.0, 110.0], "value": 105.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 118.0, 155.0, 185.0, 118.0, 141.0, 151.0, 114.0, 170.0, 116.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 163.0, 160.0, 161.0, 161.0, 161.0, 164.0, 172.0, 166.0], "value": 163.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 77.0, 163.0, 74.0, 74.0, 73.0, 95.0, 95.0, 75.0], "value": 87.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 133.0, 79.0, 78.0, 80.0, 131.0, 79.0, 79.0, 133.0], "value": 95.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 115.0, 117.0, 114.0, 114.0, 114.0, 114.0, 116.0], "value": 114.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 148.0, 148.0, 147.0, 148.0, 147.0, 149.0, 148.0, 157.0, 148.0], "value": 149.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 93.0, 94.0, 93.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 51.0, 53.0, 52.0, 53.0, 53.0, 52.0, 53.0, 55.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 37.0, 37.0, 37.0, 37.0, 37.0, 38.0, 38.0, 37.0, 38.0], "value": 37.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 76.0, 73.0, 74.0, 75.0, 76.0, 75.0, 74.0, 74.0, 73.0], "value": 74.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 120.0, 118.0, 121.0, 117.0, 122.0, 114.0, 125.0, 128.0, 161.0], "value": 125.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 177.0, 169.0, 167.0, 171.0, 168.0, 166.0, 167.0, 176.0, 167.0], "value": 170.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 64.0, 63.0, 69.0, 63.0, 65.0, 62.0, 65.0, 62.0], "value": 64.4, "unit": "score"}]}]} 11:47:37 INFO - Return code: 0 11:47:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:47:37 INFO - # TBPL SUCCESS # 11:47:37 INFO - Running post-action listener: _package_coverage_data 11:47:37 INFO - Running post-action listener: _resource_record_post_action 11:47:37 INFO - [mozharness: 2017-08-31 18:47:37.704577Z] Finished run-tests step (success) 11:47:37 INFO - Running post-run listener: _resource_record_post_run 11:47:37 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 400793600; Read time: 8; Write time: 294396 11:47:37 INFO - TinderboxPrint: CPU usage
13.1% 11:47:37 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 11:47:37 INFO - TinderboxPrint: I/O write bytes / time
400,793,600 / 294,396 11:47:37 INFO - TinderboxPrint: CPU idle
8,238.8 (86.9%) 11:47:37 INFO - TinderboxPrint: CPU user
1,192.1 (12.6%) 11:47:37 INFO - TinderboxPrint: Swap in / out
0 / 0 11:47:37 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 97599488; Read time: 0; Write time: 57788 11:47:37 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:47:37 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 283602944; Read time: 8; Write time: 189364 11:47:38 INFO - Running post-run listener: _upload_blobber_files 11:47:38 INFO - Blob upload gear active. 11:47:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:47:38 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:47:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:47:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:47:38 INFO - (blobuploader) - INFO - Open directory for files ... 11:47:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:47:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:47:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:47:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:47:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:47:39 INFO - (blobuploader) - INFO - Done attempting. 11:47:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:47:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:47:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:47:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:47:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:47:40 INFO - (blobuploader) - INFO - Done attempting. 11:47:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:47:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:47:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:47:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:47:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:47:41 INFO - (blobuploader) - INFO - Done attempting. 11:47:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:47:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:47:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:47:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:47:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:47:45 INFO - (blobuploader) - INFO - Done attempting. 11:47:45 INFO - (blobuploader) - INFO - Iteration through files over. 11:47:45 INFO - Return code: 0 11:47:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:47:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:47:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/02852011d1fe90c717f4d4bc15fd0334d61ac39952ac813049a5027d1c984e624e6729f6a8ecc733622138279917936559d7d10017f26741aafc18da7e53d0db", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/56277a6147ae91ab1045f6c0bae95e39369964479ea1199e298d525835641bb35c3ef27a377d1f3db4058ea07c572a577304275b07ba468532c9045376139ece", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fd6de1f0eb89adf30c74ac524c2374110316b253b134351a82bc74a9537e4fa90ff17af0e919d4a4135f93dada9d7d4f8a80bfd1c949a67d0dac630ad4cc0d31", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d3d0b302cf4837ee7e2c1d321df5dd06733bcce82fde9373fcdf964a044da71e2047e9bcb4ae28fbb033100af958be6663912574601685fe14dec035e1cbb40f"} 11:47:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:47:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:47:45 INFO - Contents: 11:47:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/02852011d1fe90c717f4d4bc15fd0334d61ac39952ac813049a5027d1c984e624e6729f6a8ecc733622138279917936559d7d10017f26741aafc18da7e53d0db", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/56277a6147ae91ab1045f6c0bae95e39369964479ea1199e298d525835641bb35c3ef27a377d1f3db4058ea07c572a577304275b07ba468532c9045376139ece", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fd6de1f0eb89adf30c74ac524c2374110316b253b134351a82bc74a9537e4fa90ff17af0e919d4a4135f93dada9d7d4f8a80bfd1c949a67d0dac630ad4cc0d31", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d3d0b302cf4837ee7e2c1d321df5dd06733bcce82fde9373fcdf964a044da71e2047e9bcb4ae28fbb033100af958be6663912574601685fe14dec035e1cbb40f"} 11:47:45 INFO - Running post-run listener: copy_logs_to_upload_dir 11:47:45 INFO - Copying logs to upload dir... 11:47:45 INFO - mkdir: /builds/slave/test/build/upload/logs 11:47:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.171387 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-08-31 11:47:45.733292) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-31 11:47:45.736890) ========= 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=1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810 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-Non-PGO/sha512/02852011d1fe90c717f4d4bc15fd0334d61ac39952ac813049a5027d1c984e624e6729f6a8ecc733622138279917936559d7d10017f26741aafc18da7e53d0db", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/56277a6147ae91ab1045f6c0bae95e39369964479ea1199e298d525835641bb35c3ef27a377d1f3db4058ea07c572a577304275b07ba468532c9045376139ece", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fd6de1f0eb89adf30c74ac524c2374110316b253b134351a82bc74a9537e4fa90ff17af0e919d4a4135f93dada9d7d4f8a80bfd1c949a67d0dac630ad4cc0d31", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d3d0b302cf4837ee7e2c1d321df5dd06733bcce82fde9373fcdf964a044da71e2047e9bcb4ae28fbb033100af958be6663912574601685fe14dec035e1cbb40f"} build_url:https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011457 build_url: 'https://queue.taskcluster.net/v1/task/JlZ4lx7RTwaSgq5fLFMGvQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/02852011d1fe90c717f4d4bc15fd0334d61ac39952ac813049a5027d1c984e624e6729f6a8ecc733622138279917936559d7d10017f26741aafc18da7e53d0db", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/56277a6147ae91ab1045f6c0bae95e39369964479ea1199e298d525835641bb35c3ef27a377d1f3db4058ea07c572a577304275b07ba468532c9045376139ece", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fd6de1f0eb89adf30c74ac524c2374110316b253b134351a82bc74a9537e4fa90ff17af0e919d4a4135f93dada9d7d4f8a80bfd1c949a67d0dac630ad4cc0d31", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d3d0b302cf4837ee7e2c1d321df5dd06733bcce82fde9373fcdf964a044da71e2047e9bcb4ae28fbb033100af958be6663912574601685fe14dec035e1cbb40f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-31 11:47:45.763663) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 11:47:45.764023) ========= 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=1258088b6da462a47ba2cd90000001a2-1504204015.545058-2144618810 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004504 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 11:47:45.848246) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-31 11:47:45.848588) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-31 11:47:45.848866) ========= ========= Total master_lag: 0.19 =========