builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-045 starttime: 1504722600.94 results: success (0) revision: 93dd2e456c0ecca00fb4d28744e88078a77deaf7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-06 11:30:00.943698) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-06 11:30:00.944132) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-06 11:30:00.944413) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-06 11:30:00.956594) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 11:30:00.956880) ========= 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=4300f881736b422476174874000001bc-1504722597.63712-1800445697 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-06 11:30:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 107M=0s 2017-09-06 11:30:01 (107 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131152 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 11:30:01.103374) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 11:30:01.103744) ========= 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=4300f881736b422476174874000001bc-1504722597.63712-1800445697 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016579 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 11:30:01.157890) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-06 11:30:01.158447) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 93dd2e456c0ecca00fb4d28744e88078a77deaf7 --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 93dd2e456c0ecca00fb4d28744e88078a77deaf7 --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=4300f881736b422476174874000001bc-1504722597.63712-1800445697 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-06 11:30:01,217 truncating revision to first 12 chars 2017-09-06 11:30:01,217 Setting DEBUG logging. 2017-09-06 11:30:01,217 attempt 1/10 2017-09-06 11:30:01,217 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/93dd2e456c0e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-06 11:30:01,818 unpacking tar archive at: mozilla-central-93dd2e456c0e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.007013 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-06 11:30:02.183963) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-06 11:30:02.184427) ========= script_repo_revision: 93dd2e456c0ecca00fb4d28744e88078a77deaf7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-06 11:30:02.185002) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-06 11:30:02.185271) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-06 11:30:02.199633) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-09-06 11:30:02.200103) ========= /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=4300f881736b422476174874000001bc-1504722597.63712-1800445697 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:30:02 INFO - MultiFileLogger online at 20170906 11:30:02 in /builds/slave/test 11:30:02 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:30:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:30:02 INFO - {'append_to_log': False, 11:30:02 INFO - 'base_work_dir': '/builds/slave/test', 11:30:02 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 11:30:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:30:02 INFO - 'branch': 'Firefox-Non-PGO', 11:30:02 INFO - 'buildbot_json_path': 'buildprops.json', 11:30:02 INFO - 'code_coverage': False, 11:30:02 INFO - 'config_files': ('talos/linux_config.py',), 11:30:02 INFO - 'default_actions': ('clobber', 11:30:02 INFO - 'read-buildbot-config', 11:30:02 INFO - 'download-and-extract', 11:30:02 INFO - 'populate-webroot', 11:30:02 INFO - 'create-virtualenv', 11:30:02 INFO - 'install', 11:30:02 INFO - 'setup-mitmproxy', 11:30:02 INFO - 'run-tests'), 11:30:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:30:02 INFO - 'disable_ccov_upload': False, 11:30:02 INFO - 'disable_stylo': False, 11:30:02 INFO - 'download_minidump_stackwalk': True, 11:30:02 INFO - 'download_symbols': 'ondemand', 11:30:02 INFO - 'enable_stylo': False, 11:30:02 INFO - 'enable_webrender': False, 11:30:02 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:30:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:30:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:30:02 INFO - 'gecko_profile': False, 11:30:02 INFO - 'gecko_profile_interval': 0, 11:30:02 INFO - 'installer_path': 'installer.exe', 11:30:02 INFO - 'log_level': 'info', 11:30:02 INFO - 'log_name': 'talos', 11:30:02 INFO - 'log_to_console': True, 11:30:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:30:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:30:02 INFO - 'opt_config_files': (), 11:30:02 INFO - 'pip_index': False, 11:30:02 INFO - 'suite': 'dromaeojs-e10s', 11:30:02 INFO - 'system_bits': '32', 11:30:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:30:02 INFO - 'title': 'talos-linux64-ix-045', 11:30:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:30:02 INFO - 'use_talos_json': True, 11:30:02 INFO - 'verify': 'False', 11:30:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:30:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:30:02 INFO - 'work_dir': 'build'} 11:30:02 INFO - [mozharness: 2017-09-06 18:30:02.398386Z] Running clobber step. 11:30:02 INFO - Running pre-action listener: _resource_record_pre_action 11:30:02 INFO - Running main action method: clobber 11:30:02 INFO - rmtree: /builds/slave/test/build 11:30:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:30:03 INFO - Running post-action listener: _resource_record_post_action 11:30:03 INFO - [mozharness: 2017-09-06 18:30:03.420213Z] Finished clobber step (success) 11:30:03 INFO - [mozharness: 2017-09-06 18:30:03.420311Z] Running read-buildbot-config step. 11:30:03 INFO - Running pre-action listener: _resource_record_pre_action 11:30:03 INFO - Running main action method: read_buildbot_config 11:30:03 INFO - Using buildbot properties: 11:30:03 INFO - { 11:30:03 INFO - "project": "", 11:30:03 INFO - "product": "firefox", 11:30:03 INFO - "who": "ffxbld@noreply.mozilla.org", 11:30:03 INFO - "installer_path": "public/build/target.tar.bz2", 11:30:03 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 11:30:03 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:30:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 11:30:03 INFO - "stage_platform": "linux64", 11:30:03 INFO - "basedir": "/builds/slave/test", 11:30:03 INFO - "buildnumber": 1118, 11:30:03 INFO - "platform": "ubuntu64_hw", 11:30:03 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:30:03 INFO - "slavebuilddir": "test", 11:30:03 INFO - "taskId": "RMD_mfGIS9Gg5cMh7TmK8A", 11:30:03 INFO - "branch": "mozilla-central", 11:30:03 INFO - "script_repo_revision": "production", 11:30:03 INFO - "revision": "93dd2e456c0ecca00fb4d28744e88078a77deaf7", 11:30:03 INFO - "slavename": "talos-linux64-ix-045", 11:30:03 INFO - "repo_path": "mozilla-central" 11:30:03 INFO - } 11:30:03 INFO - Finding installer, test and symbols from parent task. 11:30:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RMD_mfGIS9Gg5cMh7TmK8A'}, attempt #1 11:30:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RMD_mfGIS9Gg5cMh7TmK8A'}, attempt #1 11:30:04 INFO - Task dependencies: CNiQiOuNT32DU3L464s6DA 11:30:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA'}, attempt #1 11:30:04 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.tar.bz2 11:30:04 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.test_packages.json 11:30:04 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.crashreporter-symbols.zip 11:30:04 INFO - Running post-action listener: _resource_record_post_action 11:30:04 INFO - [mozharness: 2017-09-06 18:30:04.908908Z] Finished read-buildbot-config step (success) 11:30:04 INFO - [mozharness: 2017-09-06 18:30:04.909124Z] Running download-and-extract step. 11:30:04 INFO - Running pre-action listener: _resource_record_pre_action 11:30:04 INFO - Running main action method: download_and_extract 11:30:04 INFO - mkdir: /builds/slave/test/build/tests 11:30: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:30:04 INFO - trying https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.test_packages.json 11:30:04 INFO - Downloading https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:30:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:30:05 INFO - Downloaded 989 bytes. 11:30:05 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:30:05 INFO - Using the following test package requirements: 11:30:05 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:30:05 INFO - u'common': [u'target.common.tests.zip'], 11:30:05 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:30:05 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:30:05 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:30:05 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:30:05 INFO - u'mozbase': [u'target.common.tests.zip'], 11:30:05 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:30:05 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:30:05 INFO - u'web-platform': [u'target.common.tests.zip', 11:30:05 INFO - u'target.web-platform.tests.tar.gz'], 11:30:05 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:30:05 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:30:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.common.tests.zip 11:30:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:30:05 INFO - Fetch https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.common.tests.zip into memory 11:30:06 INFO - Content-Length response header: 55020142 11:30:06 INFO - Bytes received: 55020142 11:30:17 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:30:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.common.tests.zip 11:30:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:30:17 INFO - Fetch https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.common.tests.zip into memory 11:30:18 INFO - Content-Length response header: 55020142 11:30:18 INFO - Bytes received: 55020142 11:30:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.talos.tests.zip 11:30:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:30:30 INFO - Fetch https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.talos.tests.zip into memory 11:30:31 INFO - Content-Length response header: 14496665 11:30:31 INFO - Bytes received: 14496665 11:30: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:30:31 INFO - trying https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.tar.bz2 11:30:31 INFO - Downloading https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:30:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:30:32 INFO - Downloaded 62680086 bytes. 11:30:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.tar.bz2 11:30:32 INFO - mkdir: /builds/slave/test/properties 11:30:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:30:32 INFO - Writing to file /builds/slave/test/properties/build_url 11:30:32 INFO - Contents: 11:30:32 INFO - build_url:https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.tar.bz2 11:30:32 INFO - Running post-action listener: _resource_record_post_action 11:30:32 INFO - Running post-action listener: find_tests_for_verification 11:30:32 INFO - Running post-action listener: set_extra_try_arguments 11:30:32 INFO - [mozharness: 2017-09-06 18:30:32.620486Z] Finished download-and-extract step (success) 11:30:32 INFO - [mozharness: 2017-09-06 18:30:32.620583Z] Running populate-webroot step. 11:30:32 INFO - Running pre-action listener: _resource_record_pre_action 11:30:32 INFO - Running main action method: populate_webroot 11:30:32 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:30:32 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:32 INFO - 'tests': ['tresize', 11:30:32 INFO - 'tcanvasmark']}, 11:30:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:30:32 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:32 INFO - 'tests': ['dromaeo_css', 11:30:32 INFO - 'kraken']}, 11:30:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:30:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:30:32 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:30:32 INFO - 'talos_options': ['--disable-stylo'], 11:30:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:30:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:30:32 INFO - 'tests': ['damp', 'tps']}, 11:30:32 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:30:32 INFO - 'talos_options': ['--disable-stylo'], 11:30:32 INFO - 'tests': ['damp', 'tps']}, 11:30:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:30:32 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:32 INFO - 'tests': ['dromaeo_dom']}, 11:30:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:30:32 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:32 INFO - 'tests': ['basic_compositor_video', 11:30:32 INFO - 'glvideo']}, 11:30:32 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:30:32 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:30:32 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:30:32 INFO - 'talos_options': ['--disable-stylo'], 11:30:32 INFO - 'tests': ['ts_paint_webext', 11:30:32 INFO - 'tp5o_webext']}, 11:30:32 INFO - 'other-e10s': {'tests': ['a11yr', 11:30:32 INFO - 'ts_paint', 11:30:32 INFO - 'tpaint', 11:30:32 INFO - 'sessionrestore', 11:30:32 INFO - 'sessionrestore_many_windows', 11:30:32 INFO - 'sessionrestore_no_auto_restore', 11:30:32 INFO - 'tabpaint']}, 11:30:32 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:32 INFO - 'tests': ['a11yr', 11:30:32 INFO - 'ts_paint', 11:30:32 INFO - 'tpaint', 11:30:32 INFO - 'sessionrestore', 11:30:32 INFO - 'sessionrestore_many_windows', 11:30:32 INFO - 'sessionrestore_no_auto_restore', 11:30:32 INFO - 'tabpaint']}, 11:30:32 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:30:32 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:30:32 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:32 INFO - 'tests': ['bloom_basic_singleton']}, 11:30:32 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:32 INFO - 'tests': ['bloom_basic']}, 11:30:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:30:32 INFO - 'tsvgr_opacity', 11:30:32 INFO - 'tart', 11:30:32 INFO - 'tscrollx', 11:30:32 INFO - 'tsvg_static']}, 11:30:32 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:32 INFO - 'tests': ['tsvgx', 11:30:32 INFO - 'tsvgr_opacity', 11:30:32 INFO - 'tart', 11:30:32 INFO - 'tscrollx', 11:30:32 INFO - 'tsvg_static']}, 11:30:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:30:32 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:30:32 INFO - 'talos_options': ['--disable-stylo'], 11:30:32 INFO - 'tests': ['tp5o']}, 11:30:32 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:30:32 INFO - 'talos_options': ['--mitmproxy', 11:30:32 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:30:32 INFO - '--firstNonBlankPaint'], 11:30:32 INFO - 'tests': ['tp6_google', 11:30:32 INFO - 'tp6_youtube', 11:30:32 INFO - 'tp6_amazon', 11:30:32 INFO - 'tp6_facebook']}, 11:30:32 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:30:32 INFO - 'talos_options': ['--disable-stylo', 11:30:32 INFO - '--mitmproxy', 11:30:32 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:30:32 INFO - '--firstNonBlankPaint'], 11:30:32 INFO - 'tests': ['tp6_google', 11:30:32 INFO - 'tp6_youtube', 11:30:32 INFO - 'tp6_amazon', 11:30:32 INFO - 'tp6_facebook']}, 11:30:32 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:30:32 INFO - 'talos_options': ['--stylo-threads=1', 11:30:32 INFO - '--mitmproxy', 11:30:32 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:30:32 INFO - '--firstNonBlankPaint'], 11:30:32 INFO - 'tests': ['tp6_google', 11:30:32 INFO - 'tp6_youtube', 11:30:32 INFO - 'tp6_amazon', 11:30:32 INFO - 'tp6_facebook']}, 11:30:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:30:32 INFO - 'talos_options': ['--xperf_path', 11:30:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:30:32 INFO - 'tests': ['tp5n']}, 11:30:32 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:30:32 INFO - 'talos_options': ['--disable-stylo', 11:30:32 INFO - '--xperf_path', 11:30:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:30:32 INFO - 'tests': ['tp5n']}}} 11:30:32 INFO - Running post-action listener: _resource_record_post_action 11:30:32 INFO - [mozharness: 2017-09-06 18:30:32.635451Z] Finished populate-webroot step (success) 11:30:32 INFO - [mozharness: 2017-09-06 18:30:32.635541Z] Running create-virtualenv step. 11:30:32 INFO - Running pre-action listener: _resource_record_pre_action 11:30:32 INFO - Running main action method: create_virtualenv 11:30:32 INFO - Creating virtualenv /builds/slave/test/build/venv 11:30:32 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:30:32 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:30:32 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:30:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:30:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:30:33 INFO - Installing distribute.............................................................................................................................................................................................done. 11:30:33 INFO - Installing pip...............done. 11:30:33 INFO - Return code: 0 11:30:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:30:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:30:33 INFO - Reading from file tmpfile_stdout 11:30:33 INFO - Output received: 11:30:33 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:30:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:30: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:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30: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:30:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2343a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ea090>, '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 0x24ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e4f90>, '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': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', '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:30: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', 'pip>=1.5'] in /builds/slave/test/build 11:30: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 pip>=1.5 11:30:33 INFO - Using env: {'DISPLAY': ':0', 11:30:33 INFO - 'HOME': '/home/cltbld', 11:30:33 INFO - 'LANG': 'en_US.UTF-8', 11:30:33 INFO - 'LANGUAGE': 'en_US:en', 11:30:33 INFO - 'LOGNAME': 'cltbld', 11:30:33 INFO - 'MAIL': '/var/mail/cltbld', 11:30:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:33 INFO - 'MOZ_NO_REMOTE': '1', 11:30:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:33 INFO - 'NO_EM_RESTART': '1', 11:30:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:33 INFO - 'PWD': '/builds/slave/test', 11:30:33 INFO - 'SHELL': '/bin/bash', 11:30:33 INFO - 'SHLVL': '1', 11:30:33 INFO - 'TERM': 'linux', 11:30:33 INFO - 'TMOUT': '86400', 11:30:33 INFO - 'USER': 'cltbld', 11:30:33 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', 11:30:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:33 INFO - '_': '/tools/buildbot/bin/python'} 11:30:34 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:30:34 INFO - Downloading/unpacking pip>=1.5 11:30:34 INFO - Running setup.py egg_info for package pip 11:30:34 INFO - warning: no files found matching 'pip/cacert.pem' 11:30:34 INFO - warning: no files found matching '*.html' under directory 'docs' 11:30:34 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:30:34 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:30:34 INFO - Installing collected packages: pip 11:30:34 INFO - Found existing installation: pip 1.1 11:30:34 INFO - Uninstalling pip: 11:30:34 INFO - Successfully uninstalled pip 11:30:34 INFO - Running setup.py install for pip 11:30:34 INFO - warning: no files found matching 'pip/cacert.pem' 11:30:34 INFO - warning: no files found matching '*.html' under directory 'docs' 11:30:34 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:30:34 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:30:34 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:30:34 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:30:34 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:30:34 INFO - Successfully installed pip 11:30:34 INFO - Cleaning up... 11:30:34 INFO - Return code: 0 11:30:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:30: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:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30: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:30:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30: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', '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 0x217a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2343a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ea090>, '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 0x24ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e4f90>, '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': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', '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:30: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', 'psutil>=3.1.1'] in /builds/slave/test/build 11:30: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 psutil>=3.1.1 11:30:34 INFO - Using env: {'DISPLAY': ':0', 11:30:34 INFO - 'HOME': '/home/cltbld', 11:30:34 INFO - 'LANG': 'en_US.UTF-8', 11:30:34 INFO - 'LANGUAGE': 'en_US:en', 11:30:34 INFO - 'LOGNAME': 'cltbld', 11:30:34 INFO - 'MAIL': '/var/mail/cltbld', 11:30:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:34 INFO - 'MOZ_NO_REMOTE': '1', 11:30:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:34 INFO - 'NO_EM_RESTART': '1', 11:30:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:34 INFO - 'PWD': '/builds/slave/test', 11:30:34 INFO - 'SHELL': '/bin/bash', 11:30:34 INFO - 'SHLVL': '1', 11:30:34 INFO - 'TERM': 'linux', 11:30:34 INFO - 'TMOUT': '86400', 11:30:34 INFO - 'USER': 'cltbld', 11:30:34 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', 11:30:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:34 INFO - '_': '/tools/buildbot/bin/python'} 11:30:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:35 INFO - Downloading/unpacking psutil>=3.1.1 11:30: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:30: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:30: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:30: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:30:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:30:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:30:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:30:36 INFO - Installing collected packages: psutil 11:30:36 INFO - Running setup.py install for psutil 11:30:36 INFO - building 'psutil._psutil_linux' extension 11:30:36 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:30:36 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:30:36 INFO - building 'psutil._psutil_posix' extension 11:30:36 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:30:36 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:30:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:30:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:30:36 INFO - Successfully installed psutil 11:30:36 INFO - Cleaning up... 11:30:36 INFO - Return code: 0 11:30:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:30: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:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30: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:30:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2343a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ea090>, '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 0x24ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e4f90>, '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': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', '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:30: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:30: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 mozsystemmonitor==0.3 11:30:36 INFO - Using env: {'DISPLAY': ':0', 11:30:36 INFO - 'HOME': '/home/cltbld', 11:30:36 INFO - 'LANG': 'en_US.UTF-8', 11:30:36 INFO - 'LANGUAGE': 'en_US:en', 11:30:36 INFO - 'LOGNAME': 'cltbld', 11:30:36 INFO - 'MAIL': '/var/mail/cltbld', 11:30:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:36 INFO - 'MOZ_NO_REMOTE': '1', 11:30:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:36 INFO - 'NO_EM_RESTART': '1', 11:30:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:36 INFO - 'PWD': '/builds/slave/test', 11:30:36 INFO - 'SHELL': '/bin/bash', 11:30:36 INFO - 'SHLVL': '1', 11:30:36 INFO - 'TERM': 'linux', 11:30:36 INFO - 'TMOUT': '86400', 11:30:36 INFO - 'USER': 'cltbld', 11:30:36 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', 11:30:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:36 INFO - '_': '/tools/buildbot/bin/python'} 11:30:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:30:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:30:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:30:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:30:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:30:38 INFO - Installing collected packages: mozsystemmonitor 11:30:38 INFO - Running setup.py install for mozsystemmonitor 11:30:38 INFO - Successfully installed mozsystemmonitor 11:30:38 INFO - Cleaning up... 11:30:38 INFO - Return code: 0 11:30:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:38 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 0x217a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2343a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ea090>, '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 0x24ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e4f90>, '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': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', '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:30:38 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:30:38 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:30:38 INFO - Using env: {'DISPLAY': ':0', 11:30:38 INFO - 'HOME': '/home/cltbld', 11:30:38 INFO - 'LANG': 'en_US.UTF-8', 11:30:38 INFO - 'LANGUAGE': 'en_US:en', 11:30:38 INFO - 'LOGNAME': 'cltbld', 11:30:38 INFO - 'MAIL': '/var/mail/cltbld', 11:30:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:38 INFO - 'MOZ_NO_REMOTE': '1', 11:30:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:38 INFO - 'NO_EM_RESTART': '1', 11:30:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:38 INFO - 'PWD': '/builds/slave/test', 11:30:38 INFO - 'SHELL': '/bin/bash', 11:30:38 INFO - 'SHLVL': '1', 11:30:38 INFO - 'TERM': 'linux', 11:30:38 INFO - 'TMOUT': '86400', 11:30:38 INFO - 'USER': 'cltbld', 11:30:38 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', 11:30:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:38 INFO - '_': '/tools/buildbot/bin/python'} 11:30:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:38 INFO - Downloading/unpacking jsonschema==2.5.1 11:30: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:30: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:30: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:30: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:30:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:30:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:30:39 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:30:39 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:30:39 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:30:39 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:30:39 INFO - Downloading functools32-3.2.3-2.tar.gz 11:30:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:30:39 INFO - warning: no files found matching '*.txt' 11:30:39 INFO - no previously-included directories found matching 'build' 11:30:39 INFO - no previously-included directories found matching 'dist' 11:30:39 INFO - no previously-included directories found matching '.git*' 11:30:39 INFO - Installing collected packages: jsonschema, functools32 11:30:39 INFO - Running setup.py install for functools32 11:30:39 INFO - warning: no files found matching '*.txt' 11:30:39 INFO - no previously-included directories found matching 'build' 11:30:39 INFO - no previously-included directories found matching 'dist' 11:30:39 INFO - no previously-included directories found matching '.git*' 11:30:39 INFO - Successfully installed jsonschema functools32 11:30:39 INFO - Cleaning up... 11:30:39 INFO - Return code: 0 11:30:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:30: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:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30: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:30:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:39 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 0x217a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2343a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ea090>, '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 0x24ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e4f90>, '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': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', '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:30:39 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:30:39 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:30:39 INFO - Using env: {'DISPLAY': ':0', 11:30:39 INFO - 'HOME': '/home/cltbld', 11:30:39 INFO - 'LANG': 'en_US.UTF-8', 11:30:39 INFO - 'LANGUAGE': 'en_US:en', 11:30:39 INFO - 'LOGNAME': 'cltbld', 11:30:39 INFO - 'MAIL': '/var/mail/cltbld', 11:30:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:39 INFO - 'MOZ_NO_REMOTE': '1', 11:30:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:39 INFO - 'NO_EM_RESTART': '1', 11:30:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:39 INFO - 'PWD': '/builds/slave/test', 11:30:39 INFO - 'SHELL': '/bin/bash', 11:30:39 INFO - 'SHLVL': '1', 11:30:39 INFO - 'TERM': 'linux', 11:30:39 INFO - 'TMOUT': '86400', 11:30:39 INFO - 'USER': 'cltbld', 11:30:39 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', 11:30:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:39 INFO - '_': '/tools/buildbot/bin/python'} 11:30:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:30:40 INFO - Cleaning up... 11:30:40 INFO - Return code: 0 11:30:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:30:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:40 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 0x217a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2343a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ea090>, '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 0x24ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e4f90>, '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': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', '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:30:40 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:30:40 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:30:40 INFO - Using env: {'DISPLAY': ':0', 11:30:40 INFO - 'HOME': '/home/cltbld', 11:30:40 INFO - 'LANG': 'en_US.UTF-8', 11:30:40 INFO - 'LANGUAGE': 'en_US:en', 11:30:40 INFO - 'LOGNAME': 'cltbld', 11:30:40 INFO - 'MAIL': '/var/mail/cltbld', 11:30:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:40 INFO - 'MOZ_NO_REMOTE': '1', 11:30:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:40 INFO - 'NO_EM_RESTART': '1', 11:30:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:40 INFO - 'PWD': '/builds/slave/test', 11:30:40 INFO - 'SHELL': '/bin/bash', 11:30:40 INFO - 'SHLVL': '1', 11:30:40 INFO - 'TERM': 'linux', 11:30:40 INFO - 'TMOUT': '86400', 11:30:40 INFO - 'USER': 'cltbld', 11:30:40 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', 11:30:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:40 INFO - '_': '/tools/buildbot/bin/python'} 11:30:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:40 INFO - Downloading/unpacking blobuploader==1.2.4 11:30:40 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:30:40 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:30:40 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:30:40 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:30:41 INFO - Downloading blobuploader-1.2.4.tar.gz 11:30:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:30:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:30:41 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:30:41 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:30:41 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:30:41 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:30:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:30:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:30:41 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:30:41 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:30:41 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:30:41 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:30:41 INFO - Downloading docopt-0.6.1.tar.gz 11:30:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:30:42 INFO - Installing collected packages: blobuploader, requests, docopt 11:30:42 INFO - Running setup.py install for blobuploader 11:30:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:30:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:30:42 INFO - Running setup.py install for requests 11:30:42 INFO - Running setup.py install for docopt 11:30:42 INFO - Successfully installed blobuploader requests docopt 11:30:42 INFO - Cleaning up... 11:30:42 INFO - Return code: 0 11:30:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:30: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:30:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30: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:30:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:42 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 0x217a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2343a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ea090>, '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 0x24ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e4f90>, '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': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', '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:30:42 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:30:42 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:30:42 INFO - Using env: {'DISPLAY': ':0', 11:30:42 INFO - 'HOME': '/home/cltbld', 11:30:42 INFO - 'LANG': 'en_US.UTF-8', 11:30:42 INFO - 'LANGUAGE': 'en_US:en', 11:30:42 INFO - 'LOGNAME': 'cltbld', 11:30:42 INFO - 'MAIL': '/var/mail/cltbld', 11:30:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:42 INFO - 'MOZ_NO_REMOTE': '1', 11:30:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:42 INFO - 'NO_EM_RESTART': '1', 11:30:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:42 INFO - 'PWD': '/builds/slave/test', 11:30:42 INFO - 'SHELL': '/bin/bash', 11:30:42 INFO - 'SHLVL': '1', 11:30:42 INFO - 'TERM': 'linux', 11:30:42 INFO - 'TMOUT': '86400', 11:30:42 INFO - 'USER': 'cltbld', 11:30:42 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', 11:30:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:42 INFO - '_': '/tools/buildbot/bin/python'} 11:30:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:30:42 INFO - Running setup.py (path:/tmp/pip-ExiGzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:30:42 INFO - Running setup.py (path:/tmp/pip-uJ9rTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:30:43 INFO - Running setup.py (path:/tmp/pip-ZJ_lUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:30:43 INFO - Running setup.py (path:/tmp/pip-Va5uM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:30:43 INFO - Running setup.py (path:/tmp/pip-paLJNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:30:43 INFO - Running setup.py (path:/tmp/pip-jhWfiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:30:43 INFO - Running setup.py (path:/tmp/pip-_BnCcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:30:43 INFO - Running setup.py (path:/tmp/pip-dN9VUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:30:43 INFO - Running setup.py (path:/tmp/pip-UJMoze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:30:43 INFO - Running setup.py (path:/tmp/pip-9cuFNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:30:43 INFO - Running setup.py (path:/tmp/pip-o8EpBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:30:43 INFO - Running setup.py (path:/tmp/pip-KH6llD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:30:43 INFO - Running setup.py (path:/tmp/pip-pIqmSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:30:43 INFO - Running setup.py (path:/tmp/pip-PL6GPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:30:43 INFO - Running setup.py (path:/tmp/pip-gLyMDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:30:43 INFO - Running setup.py (path:/tmp/pip-a3CaiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:30:44 INFO - Running setup.py (path:/tmp/pip-nysLSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:30:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:30:44 INFO - Running setup.py install for manifestparser 11:30:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:30:44 INFO - Running setup.py install for mozcrash 11:30:44 INFO - Running setup.py install for mozdebug 11:30:44 INFO - Running setup.py install for mozdevice 11:30:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:30:44 INFO - Running setup.py install for mozfile 11:30:44 INFO - Running setup.py install for mozhttpd 11:30:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:30:44 INFO - Running setup.py install for mozinfo 11:30:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:30:44 INFO - Running setup.py install for mozInstall 11:30:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:30:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:30:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:30:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:30:44 INFO - Running setup.py install for mozleak 11:30:44 INFO - Running setup.py install for mozlog 11:30:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:30:45 INFO - Running setup.py install for moznetwork 11:30:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:30:45 INFO - Running setup.py install for mozprocess 11:30:45 INFO - Running setup.py install for mozprofile 11:30:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:30:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:30:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:30:45 INFO - Running setup.py install for mozrunner 11:30:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:30:45 INFO - Running setup.py install for mozscreenshot 11:30:45 INFO - Running setup.py install for moztest 11:30:45 INFO - Running setup.py install for mozversion 11:30:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:30:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:30:45 INFO - Cleaning up... 11:30:45 INFO - Return code: 0 11:30:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:30: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:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30: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:30:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:45 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 0x217a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2343a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ea090>, '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 0x24ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e4f90>, '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': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', '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:30:45 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:30:45 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:30:45 INFO - Using env: {'DISPLAY': ':0', 11:30:45 INFO - 'HOME': '/home/cltbld', 11:30:45 INFO - 'LANG': 'en_US.UTF-8', 11:30:45 INFO - 'LANGUAGE': 'en_US:en', 11:30:45 INFO - 'LOGNAME': 'cltbld', 11:30:45 INFO - 'MAIL': '/var/mail/cltbld', 11:30:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:45 INFO - 'MOZ_NO_REMOTE': '1', 11:30:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:45 INFO - 'NO_EM_RESTART': '1', 11:30:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:45 INFO - 'PWD': '/builds/slave/test', 11:30:45 INFO - 'SHELL': '/bin/bash', 11:30:45 INFO - 'SHLVL': '1', 11:30:45 INFO - 'TERM': 'linux', 11:30:45 INFO - 'TMOUT': '86400', 11:30:45 INFO - 'USER': 'cltbld', 11:30:45 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', 11:30:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:45 INFO - '_': '/tools/buildbot/bin/python'} 11:30:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:30:46 INFO - Running setup.py (path:/tmp/pip-lbszGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:30:46 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:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:30:46 INFO - Running setup.py (path:/tmp/pip-cG79yd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:30:46 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:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:30:46 INFO - Running setup.py (path:/tmp/pip-IiHf9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:30:46 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:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:30:46 INFO - Running setup.py (path:/tmp/pip-Vhpq3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:30:46 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:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:30:46 INFO - Running setup.py (path:/tmp/pip-mabSC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:30:46 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:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:30:46 INFO - Running setup.py (path:/tmp/pip-WRSfAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:30:46 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:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:30:46 INFO - Running setup.py (path:/tmp/pip-lsjQc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:30:46 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:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:30:46 INFO - Running setup.py (path:/tmp/pip-eAbyPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:30:46 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:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:30:46 INFO - Running setup.py (path:/tmp/pip-2D2CxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:30:46 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:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:30:46 INFO - Running setup.py (path:/tmp/pip-m5bTjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:30:46 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:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:30:46 INFO - Running setup.py (path:/tmp/pip-FQ9ktG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:30:46 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:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:30:46 INFO - Running setup.py (path:/tmp/pip-UViYiP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:30:46 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:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:30:46 INFO - Running setup.py (path:/tmp/pip-ZEmyRN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:30:46 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:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:30:47 INFO - Running setup.py (path:/tmp/pip-bR9QvP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:30:47 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:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:30:47 INFO - Running setup.py (path:/tmp/pip-Qszpol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:30:47 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:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:30:47 INFO - Running setup.py (path:/tmp/pip-koto7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:30:47 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:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:30:47 INFO - Running setup.py (path:/tmp/pip-oudeJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:30:47 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:30:47 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:30:47 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:30:47 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:30:47 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:30:47 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:30:47 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:30:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:30:47 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:30:47 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:30:47 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:30:47 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:30:49 INFO - Downloading blessings-1.6.tar.gz 11:30:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:30:49 INFO - Installing collected packages: blessings 11:30:49 INFO - Running setup.py install for blessings 11:30:49 INFO - Successfully installed blessings 11:30:49 INFO - Cleaning up... 11:30:49 INFO - Return code: 0 11:30:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:30:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:30:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:30:49 INFO - Reading from file tmpfile_stdout 11:30:49 INFO - Current package versions: 11:30:49 INFO - argparse == 1.2.1 11:30:49 INFO - blessings == 1.6 11:30:49 INFO - blobuploader == 1.2.4 11:30:49 INFO - docopt == 0.6.1 11:30:49 INFO - functools32 == 3.2.3-2 11:30:49 INFO - jsonschema == 2.5.1 11:30:49 INFO - manifestparser == 1.1 11:30:49 INFO - mozInstall == 1.13 11:30:49 INFO - mozcrash == 1.0 11:30:49 INFO - mozdebug == 0.1 11:30:49 INFO - mozdevice == 0.51 11:30:49 INFO - mozfile == 1.2 11:30:49 INFO - mozhttpd == 0.7 11:30:49 INFO - mozinfo == 0.10 11:30:49 INFO - mozleak == 0.1 11:30:49 INFO - mozlog == 3.5 11:30:49 INFO - moznetwork == 0.27 11:30:49 INFO - mozprocess == 0.25 11:30:49 INFO - mozprofile == 0.28 11:30:49 INFO - mozrunner == 6.13 11:30:49 INFO - mozscreenshot == 0.1 11:30:49 INFO - mozsystemmonitor == 0.3 11:30:49 INFO - moztest == 0.8 11:30:49 INFO - mozversion == 1.4 11:30:49 INFO - psutil == 3.1.1 11:30:49 INFO - requests == 1.2.3 11:30:49 INFO - wsgiref == 0.1.2 11:30:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:30:49 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:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30:49 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:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:49 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 0x217a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2343a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ea090>, '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 0x24ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e4f90>, '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': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', '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:30:49 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:30:49 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:30:49 INFO - Using env: {'DISPLAY': ':0', 11:30:49 INFO - 'HOME': '/home/cltbld', 11:30:49 INFO - 'LANG': 'en_US.UTF-8', 11:30:49 INFO - 'LANGUAGE': 'en_US:en', 11:30:49 INFO - 'LOGNAME': 'cltbld', 11:30:49 INFO - 'MAIL': '/var/mail/cltbld', 11:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:49 INFO - 'MOZ_NO_REMOTE': '1', 11:30:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:49 INFO - 'NO_EM_RESTART': '1', 11:30:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:49 INFO - 'PWD': '/builds/slave/test', 11:30:49 INFO - 'SHELL': '/bin/bash', 11:30:49 INFO - 'SHLVL': '1', 11:30:49 INFO - 'TERM': 'linux', 11:30:49 INFO - 'TMOUT': '86400', 11:30:49 INFO - 'USER': 'cltbld', 11:30:49 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', 11:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:49 INFO - '_': '/tools/buildbot/bin/python'} 11:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:50 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:30:50 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:30:50 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:30:50 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:30:50 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:30:50 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:30:50 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:30:50 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:30:50 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:30:50 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:30:50 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:30:50 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:30:50 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:30:50 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:30:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:30:51 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:30:51 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:30:51 INFO - Installing collected packages: simplejson 11:30:51 INFO - Running setup.py install for simplejson 11:30:51 INFO - building 'simplejson._speedups' extension 11:30:51 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:30:52 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:30:52 INFO - Successfully installed simplejson 11:30:52 INFO - Cleaning up... 11:30:52 INFO - Return code: 0 11:30:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:30:52 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:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30:52 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:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:52 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 0x217a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2343a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ea090>, '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 0x24ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e4f90>, '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': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', '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:30:52 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:30:52 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:30:52 INFO - Using env: {'DISPLAY': ':0', 11:30:52 INFO - 'HOME': '/home/cltbld', 11:30:52 INFO - 'LANG': 'en_US.UTF-8', 11:30:52 INFO - 'LANGUAGE': 'en_US:en', 11:30:52 INFO - 'LOGNAME': 'cltbld', 11:30:52 INFO - 'MAIL': '/var/mail/cltbld', 11:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:52 INFO - 'MOZ_NO_REMOTE': '1', 11:30:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:52 INFO - 'NO_EM_RESTART': '1', 11:30:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:52 INFO - 'PWD': '/builds/slave/test', 11:30:52 INFO - 'SHELL': '/bin/bash', 11:30:52 INFO - 'SHLVL': '1', 11:30:52 INFO - 'TERM': 'linux', 11:30:52 INFO - 'TMOUT': '86400', 11:30:52 INFO - 'USER': 'cltbld', 11:30:52 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', 11:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:52 INFO - '_': '/tools/buildbot/bin/python'} 11:30:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:30:52 INFO - Cleaning up... 11:30:52 INFO - Return code: 0 11:30:52 INFO - Running post-action listener: _resource_record_post_action 11:30:52 INFO - Running post-action listener: _start_resource_monitoring 11:30:52 INFO - Starting resource monitoring. 11:30:52 INFO - [mozharness: 2017-09-06 18:30:52.532716Z] Finished create-virtualenv step (success) 11:30:52 INFO - [mozharness: 2017-09-06 18:30:52.533228Z] Running install step. 11:30:52 INFO - Running pre-action listener: _resource_record_pre_action 11:30:52 INFO - Running main action method: install 11:30:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:30:52 INFO - Reading from file tmpfile_stdout 11:30:52 INFO - Detecting whether we're running mozinstall >=1.0... 11:30:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:30:52 INFO - Reading from file tmpfile_stdout 11:30:52 INFO - Output received: 11:30:52 INFO - Usage: mozinstall [options] installer 11:30:52 INFO - Options: 11:30:52 INFO - -h, --help show this help message and exit 11:30:52 INFO - -d DEST, --destination=DEST 11:30:52 INFO - Directory to install application into. [default: 11:30:52 INFO - "/builds/slave/test"] 11:30:52 INFO - --app=APP Application being installed. [default: firefox] 11:30:52 INFO - mkdir: /builds/slave/test/build/application 11:30:52 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:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:31:09 INFO - Reading from file tmpfile_stdout 11:31:09 INFO - Output received: 11:31:09 INFO - /builds/slave/test/build/application/firefox/firefox 11:31:09 INFO - Running post-action listener: _resource_record_post_action 11:31:09 INFO - [mozharness: 2017-09-06 18:31:09.378520Z] Finished install step (success) 11:31:09 INFO - [mozharness: 2017-09-06 18:31:09.378767Z] Running setup-mitmproxy step. 11:31:09 INFO - Running pre-action listener: _resource_record_pre_action 11:31:09 INFO - Running main action method: setup_mitmproxy 11:31:09 INFO - Skipping: mitmproxy is not required 11:31:09 INFO - Running post-action listener: _resource_record_post_action 11:31:09 INFO - [mozharness: 2017-09-06 18:31:09.379780Z] Finished setup-mitmproxy step (success) 11:31:09 INFO - [mozharness: 2017-09-06 18:31:09.379880Z] Running run-tests step. 11:31:09 INFO - Running pre-action listener: _resource_record_pre_action 11:31:09 INFO - Running pre-action listener: _set_gcov_prefix 11:31:09 INFO - Running main action method: run_tests 11:31:09 WARNING - Try message not found. 11:31:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:31:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:31:09 INFO - Python 2.7.3 11:31:09 INFO - Return code: 0 11:31:09 INFO - grabbing minidump binary from tooltool 11:31:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:09 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 0x24ea090>, '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 0x24ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e4f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:31:09 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:31:09 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:31:09 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:31:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:31:09 INFO - Return code: 0 11:31:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:31:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:31:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:31:09 INFO - ENV: RUST_BACKTRACE is now full 11:31:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:31:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:31:09 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/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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:31:09 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/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-045 --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:31:09 INFO - Using env: {'DISPLAY': ':0', 11:31:09 INFO - 'HOME': '/home/cltbld', 11:31:09 INFO - 'LANG': 'en_US.UTF-8', 11:31:09 INFO - 'LANGUAGE': 'en_US:en', 11:31:09 INFO - 'LOGNAME': 'cltbld', 11:31:09 INFO - 'MAIL': '/var/mail/cltbld', 11:31:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:31:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:31:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:09 INFO - 'MOZ_NO_REMOTE': '1', 11:31:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:31:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:09 INFO - 'NO_EM_RESTART': '1', 11:31:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:09 INFO - 'PWD': '/builds/slave/test', 11:31:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:31:09 INFO - 'RUST_BACKTRACE': 'full', 11:31:09 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:31:09 INFO - 'SHELL': '/bin/bash', 11:31:09 INFO - 'SHLVL': '1', 11:31:09 INFO - 'TERM': 'linux', 11:31:09 INFO - 'TMOUT': '86400', 11:31:09 INFO - 'USER': 'cltbld', 11:31:09 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', 11:31:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:09 INFO - '_': '/tools/buildbot/bin/python'} 11:31:09 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/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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:31:09 INFO - mozversion application_buildid: 20170906173802 11:31:09 INFO - mozversion application_changeset: 93dd2e456c0ecca00fb4d28744e88078a77deaf7 11:31:09 INFO - mozversion application_display_name: Nightly 11:31:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:31:09 INFO - mozversion application_name: Firefox 11:31:09 INFO - mozversion application_remotingname: firefox 11:31:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:31:09 INFO - mozversion application_vendor: Mozilla 11:31:09 INFO - mozversion application_version: 57.0a1 11:31:09 INFO - mozversion platform_buildid: 20170906173802 11:31:09 INFO - mozversion platform_changeset: 93dd2e456c0ecca00fb4d28744e88078a77deaf7 11:31:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:31:09 INFO - mozversion platform_version: 57.0a1 11:31:09 INFO - using testdate: 1504722669 11:31:09 INFO - actual date: 1504722669 11:31:09 INFO - starting webserver on 'localhost:49216' 11:31:09 INFO - SUITE-START | Running 2 tests 11:31:09 INFO - TEST-START | dromaeo_css 11:31:09 INFO - Initialising browser for dromaeo_css test... 11:31:09 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49216/getInfo.html -profile /tmp/tmpGJSqES/profile 11:31:09 INFO - TEST-INFO | started process 5555 (/builds/slave/test/build/application/firefox/firefox http://localhost:49216/getInfo.html) 11:31:15 INFO - TEST-INFO | 5555: exit 0 11:31:16 INFO - Browser initialized. 11:31:16 INFO - Running cycle 1/1 for dromaeo_css test... 11:31:16 INFO - Using env: {'DISPLAY': ':0', 11:31:16 INFO - 'HOME': '/home/cltbld', 11:31:16 INFO - 'JSGC_DISABLE_POISONING': '1', 11:31:16 INFO - 'LANG': 'en_US.UTF-8', 11:31:16 INFO - 'LANGUAGE': 'en_US:en', 11:31:16 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:31:16 INFO - 'LOGNAME': 'cltbld', 11:31:16 INFO - 'MAIL': '/var/mail/cltbld', 11:31:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:31:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:31:16 INFO - 'MOZ_CRASHREPORTER': '1', 11:31:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:16 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:31:16 INFO - 'MOZ_NO_REMOTE': '1', 11:31:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:31:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:16 INFO - 'NO_EM_RESTART': '1', 11:31:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:16 INFO - 'PWD': '/builds/slave/test', 11:31:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:31:16 INFO - 'RUST_BACKTRACE': 'full', 11:31:16 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:31:16 INFO - 'SHELL': '/bin/bash', 11:31:16 INFO - 'SHLVL': '1', 11:31:16 INFO - 'TERM': 'linux', 11:31:16 INFO - 'TMOUT': '86400', 11:31:16 INFO - 'USER': 'cltbld', 11:31:16 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', 11:31:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:16 INFO - '_': '/tools/buildbot/bin/python'} 11:31:16 INFO - TEST-INFO | started process 5757 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGJSqES/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:31:17 INFO - PID 5757 | 11:31:17 INFO - PID 5757 | (/builds/slave/test/build/application/firefox/firefox:5809): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:31:17 INFO - PID 5757 | 11:31:27 INFO - PID 5757 | RSS: Main: 167436288 11:31:27 INFO - PID 5757 | 11:34:24 INFO - PID 5757 | Cycle 1(1): loaded http://localhost:49216/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49216/tests/dromaeo/cssquery-ext.html) 11:34:25 INFO - PID 5757 | RSS: Main: 173998080 11:34:25 INFO - PID 5757 | 11:37:27 INFO - PID 5757 | Cycle 1(1): loaded http://localhost:49216/tests/dromaeo/cssquery-ext.html (next: http://localhost:49216/tests/dromaeo/cssquery-jquery.html) 11:37:28 INFO - PID 5757 | RSS: Main: 171048960 11:37:28 INFO - PID 5757 | 11:40:30 INFO - PID 5757 | Cycle 1(1): loaded http://localhost:49216/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49216/tests/dromaeo/cssquery-mootools.html) 11:40:30 INFO - PID 5757 | RSS: Main: 173637632 11:40:30 INFO - PID 5757 | 11:43:32 INFO - PID 5757 | Cycle 1(1): loaded http://localhost:49216/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49216/tests/dromaeo/cssquery-prototype.html) 11:43:32 INFO - PID 5757 | RSS: Main: 171913216 11:43:32 INFO - PID 5757 | 11:46:36 INFO - PID 5757 | Cycle 1(1): loaded http://localhost:49216/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49216/tests/dromaeo/cssquery-yui.html) 11:46:36 INFO - PID 5757 | RSS: Main: 173883392 11:46:36 INFO - PID 5757 | 11:49:38 INFO - PID 5757 | Cycle 1(1): loaded http://localhost:49216/tests/dromaeo/cssquery-yui.html (next: http://localhost:49216/tests/dromaeo/cssquery-dojo.html) 11:49:38 INFO - PID 5757 | RSS: Main: 175644672 11:49:38 INFO - PID 5757 | 11:49:38 INFO - PID 5757 | __start_tp_report 11:49:38 INFO - PID 5757 | _x_x_mozilla_page_load 11:49:38 INFO - PID 5757 | _x_x_mozilla_page_load_details 11:49:38 INFO - PID 5757 | |i|pagename|runs| 11:49:38 INFO - PID 5757 | |0;dojo.html;3256.5929258842643;3280.1799550112473;3280.7211387032103;3281.7374610031193;3315.7347412207027;6430.967845160773;6477.838054048651;6526.608403495794;6528.379803918626;6572.6056436613835;6297.464715499188;6434.420902118808;6444.838879028027;6459.321771214014;6470.805875823721;5975.133605627181;6022.638641681502;6043.305019922699;6053.788117415891;6079.148919151322;5772.5670574706855;6002.279726432834;6041.879162416749;6087.4825639820665;6149.477294429978;5855.385184555625;5946.1675365448655;5999.640021598696;6040.214772079624;6052.727627256784;5914.526837852962;5954.583179177459;6021.458068773832;6042.9395706042815;6045.546584006217;5793.13103034544;5846.298444186725;5858.414158584107;5888.175655408247;5904.704764761787;6442.226932768098;6522.902156467657;6546.312637173076;6567.67161641916;6578.506612004119;6851;6925.58446493212;6934.682410342019;6935.72257109715;6972.651367431608;6932.792016239521;6937.583744975267;6945.062416573799;7029.156501219886;7029.929700702979;6335.334789847046;6457.741690332381;6460.450861676763;6504.9674751625935;6539.6403197824575;7187.065681461481;7224.422046236288;7260.310270524292;7263.34629883313;7282.963585182146;6830.658467076626;6843.144606924135;6922.307769223037;6938.132733408324;6938.653067346612;6800.08198893153;6950.339717726808;6988.19635741886;7064.788056358318;7067.5052746307265;6869.862602748017;6904.171499420001;6961.860762784716;6991.265917078736;7005.754798582026;6814.182298124257;6940.757073502504;6986.545874518241;7018.649067546602;7019.92980070203;5458.6724796512335;5463.945360546423;5475.0418676731415;5490.780368785204;5532.7786888524815;4803.735794531334;4844.975775121102;4860.708357498562;4862.586680132157;4867.123917694778;6081.3614570470345;6082.878342433127;6158.568900177034;6161.291451483047;6164.599301045418;6797.354251346114;6959.164900212007;6991.685374158175;6991.7902462926195;7093.361597456254;5999.820005399845;6024.879502409927;6045.486133678599;6058.030715085565;6074.574779765462;5982.192404025401;5982.850428739316;5989.071693887455;5991.191189189491;5992.041273396236;6585.9012114818315;6636.336366363422;6673.299303573152;6675.566088204251;6696.46428285736;6965.303469652994;6997.475189360818;7004.3696067354185;7035.05026821393;7054.329838665319;6622.304658011032;6650.6342151180215;6725.125733654593;6727.226368967631;6740.258571557027;6680.5991640501725;6798.558093723892;6837.692303846438;6858.725650974105;6886.51794374389;6969.895451568129;7019.5788252705;7042.154941407064;7053.0830991970715;7075.044868942627;6717.395434410926;6800.965995169992;6801.18385793708;6805.455563554805;6810.421114205348;6725.327467253235;6726.966365168143;6736.966315168393;6812.693428795617;6824.317568243136;6503.122078519339;6599.439047681001;6611.768588099393;6623;6630.569013014138;6695.564788288746;6709.429698475684;6762.222344430454;6769.492288078315;6785.321467853372;6540.345965403421;6567.507436942153;6632.2372927114;6661.833454163685;6674.232463266787 11:49:38 INFO - PID 5757 | |1;ext.html;656.3600489522713;741.5365396627108;750.6659536506257;751.1324420294554;761.136110514566;30182.547261791064;38770.80614596927;39927.99999999993;39935.40096898545;39988.60011399885;39764.60235397645;39994.400223991004;39994.8000259999;40067;40117.7994110029;7435.85128297435;7486.401087912981;7668.041494813148;7697.268759467842;7781.96109019457;7569.810754731148;7602.581857997808;7734.1105772836;7734.806629834243;7735.419843511731;7602.277783610548;7679.6928122875015;7753.883691744629;7759.379249660042;7762.883556746625;7684.884726729104;7685.538867667958;7686.577238251922;7687.692492300302;7687.730929417499;7585.089789225273;7660.502067365604;7662.463627546074;7662.616869156575;7665.5783931883725;37144.628553714385;37511;37563.81218093892;37871.621283787084;37899.24201515954;3486.581610206766;3529.047157267549;3533.3639944809925;3546.929061418783;3548.787072775616;30390.848045759853;30498.695013049808;30517.542236866466;30559.38881222363;30663.69336306653;5007.349044624212;5147.794088236466;5187.118189907687;5205.193195054772;5257.158854583248;185287;186802;187352;187352;187931;47348.28977565339;47449.28826067612;47471.287930681065;47608.285875711896;47696;49732.254016189785;49734;49918.500814991385;49929.50070499321;49975.50024499781;12775.936120319524;14845.109293442429;14997.550073497594;15061.548153555412;15062.623434414227;10466.424346661006;12127.878721212852;12337.50649974009;12387.690307742198;12394.380280986095;12994.805077923838;13129;13139.277339746406;13159.60521184366;13273.933630331787;5379.266245703756;5400;5407.432219616962;5459.508644222039;5507.614466987353;1011.9747006324901;1020.8519764634235;1033.6382266206767;1045.1429827541342;1050.1021626509225;22285;22714.43213919632;22781.772182277964;22811.201607943643;22871.88564057202;1133.484264659578;1136.8181091025397;1137.3062431916524;1138.7494751154732;1141.2467771271088;12007.519699211934;12170.452329645188;12178.756424871453;12178.75642487163;12222.327771972448;222995;224219;224502;224537;225516;49999.25001124913;50123.49876501261;50161.24758128631;50269.49730502722;50363.24455133176;1438.4246301479284;1462.8464011278663;1463.743844827151;1467.7798330250548;1487.152323175801;21808;22361.552768944854;26231.475370492866;26956.982369168316;27284;45881.311780322656;48338.75830620824;48473.5152648469;48473.75763121162;48500.272495911886;199297;201380;201391;201521;201598;29024.854875725487;29784.55323170111;31576.36847263088;32105.35789284248;32198.678013220506;39960;40305.79847100746;40309.596904030586;40638.18723625452;40775.38836917508;29915.401691966475;30074;30202.395952080395;30773.23066923255;30987;31026.069217923497;32321.676783232808;32437.67562324441;32891.17772055718;33044;36232.63767362398;36294.8185259072;36318;36595.45106823453;36667.26665466622;30616.91621853972;31321.37357252888;31869.521957171124;32005.519917200796;32540.186495337806;23906;24020.39949001289;24414.75585244196;24574.877125614257;24911.75088249167 11:49:38 INFO - PID 5757 | |2;jquery.html;552.2930648769574;555.5749851363707;567.8012695556556;570.7517230004945;580.1790466489917;21689.783102168974;22208.55582888341;22360.552788944213;22429.887850560746;22438.214662486796;26443;27115.864420677917;27265.863670681618;27293.45413091737;27301;6980.19727731311;7213.855722885539;7237.240089790575;7251.057362542889;7252.818679533001;6670.7331706731675;6963;6994.755183568576;6997.860042799141;7034.05040319558;6992.5105242633035;6997.44020478363;7003.7548685795755;7007.299270073003;7013.544119632207;6967.407770339527;6982.89525657113;6989.301069893047;6991.056207412011;6996.1604607446925;6851.931480685179;6858.245592984767;6860.862782744367;6861.279565645636;6863.691133898937;29471.852640736874;30396.54405183924;30530.84734576313;30625.540616890765;30628.234294142825;2124.6812978053417;2147.2162660628915;2152.827773778094;2157.471419502216;2166.566686662674;20554;21264.14943402262;21316.68024979611;21337.35987920365;21337.786622133735;2452.288836237489;2464.445499762557;2528.9873550632315;2578.2136448383235;2584.831985920909;133066.33466832605;135392.32303838516;135961.32019339938;135963.32018339945;136031.31984340114;19253.32613358526;21866;21896.89051554732;21936.561268774854;22004.229851955104;44246.77876610596;44282;44440.3333949991;44461.333080003824;44525.332120018225;10541.947290263499;10577.57689692419;10650.414227217423;10739.83890241647;10903.727406814734;8260.876086858701;8651.178138076873;8681.131886811268;8703.911061345232;8761.430507017023;10517.316374435637;11201.60794372193;11334.319940803578;11396.088312934946;11449.542018319175;3520.735944804099;3604.4232922732235;3723.422869455239;3915.8629447969756;4183.330667093251;951.7668171298076;975.1467465967279;975.2539307429822;976.8947917238363;1003.0821798054844;19322.903385483263;19409.8059019408;19410.02949852502;19729.40811775649;20179.495512612302;1016.8932262112372;1030.5929157982705;1038.4081073787868;1046.7906418716286;1054.8681414823147;10605.73485662849;10835.18736094796;11040.447977601088;11071.280366776295;11214.31853139169;500997;504732;516727;520995;523484;106677;107269;108004;111468;121717.39141304414;1191.4817054581285;1209.0690168570152;1253.1415980053876;1254.3978890132787;1261.0920137500984;20706.171753129707;20872.895635521727;21024.159033638483;21124.577508449438;21384.78615213828;57408.71295643495;57468;58146.70926645509;58614.4138558609;61564.69217653883;69290;69492.65253673699;69626.30373696199;69641.65179174072;70195.64902175457;12176.939115304722;14386.208758518382;15077.095374277578;15135.621609459411;15209.923950380176;23141.88429057844;23514.882425587763;23548.764512354657;23744.287671369886;23907.16324928619;18360.173792179117;19114.52213694669;19150.71273930864;19157.8084219156;19170.71243931314;16769.661939041944;16910.40813571519;16960.321587136375;17000.57498562546;17339.2197351122;19031.809681903003;19267.22931082741;19311.517212069248;19454.805451945864;19464.1241144146;14620.634484137981;14647.853521465075;14736.042157259744;14756.262186890826;14812.62968425798;18356.541086472946;18719.344822931133;18800.15399307007;18889.433317000512;18906.14922328526 11:49:38 INFO - PID 5757 | |3;mootools.html;1300.3108352573136;1360.625827897328;1365.1536047650452;1370.300826974552;1371.7873729571916;14920.850791492096;15553.300101495432;15559.688806223883;15574.221288935536;15962;15877.920610396932;16132.112733799637;16204;16215.594610134753;16219.107949062796;2706.161090062082;2708.8103832731717;2752.1055656911485;2754.6832114306812;2756.2558109310476;2783.554631258999;2847.373577812878;2848.857557122146;2848.957265641007;2850.2589326774983;2709.607106969488;2725.441284536665;2738.8219765715266;2748.69764325925;2765.7925655575814;2684.8657567121663;2689.4217743185213;2714.9321266968286;2719.809613327068;2726.6864310604337;2659.734026597344;2663.1211700138906;2687.7177896320995;2699.7030326664053;2706.120510833987;14093.083949543246;14323.78514322286;14374.20941848198;14487.710245795026;14506.927465362605;7438.702451901918;7679.654415551314;7693.19221481742;7702.268284512962;7725.652345644459;5158.97420512895;5221.973890130525;5303.151495760699;5396.892062158735;5413.675179489243;1915.3775023117541;1931.51712071982;1936.4674714453497;1986.9006549672458;2000.9299674511467;26399.73600263992;27098.5935210972;27124.050658226868;27137.457250855266;27182.5922611161;6843.726250949906;6889.311068893071;6929.757458488929;7111.324424179798;7120.287971202838;20058.799412005985;20066.698999515018;20067.799322006886;20109.994500274926;20122.597548048958;8018.358531317481;8165.95917020423;8170;8180.386471014579;8183.71357002502;7190.460715446363;7210.747623833141;7289.599072050982;7329.047223860864;7368.815779605446;5678.829635110953;5734.541236701054;5828.038373668297;5843.152742852262;5865.178874957509;1715.5796829776784;1783.4649605118414;1796.6047469556677;1798.6762382771226;1810.4568629411124;903.7785742493131;931.189864817613;936.4615346175976;940.2102234123232;945.6690158444627;7598.620068996528;7616.238376162451;7701.268379503938;7725.5364678119495;7732.381409487228;994.7065615643403;1016.9644062457778;1021.918246540275;1024.8462730590322;1026.630413051301;5281.762320695578;5358.437364076716;5511.4213007633625;5564.527015203672;5754.625949313281;21637.459063523536;21720.131194752033;22042.228522001653;22185.44536386603;22216;19956.10197541114;21282.36152915415;21297.148114075262;21352.572948540943;21751.12995480195;11137.498812553455;11465.254758440842;11526.88473115275;11528.250663706833;11528.942355288338;8447.831043378976;8756.343274254328;9059.909400905906;9156.6795162169;9202.677906273248;18025;18154.636907262044;18533;18809.247630094644;18903.527411814815;18812;18935.715964260824;19065.618687626447;19271.1327990238;19878.403647890587;12081.335526546125;12096.879031209575;12346.197497162655;12377.752444951231;12379.257244564995;13926.651833704238;14086.718265634834;14096.295185240902;14097.718045638825;14103.153810771386;11274.492647831032;11393.259438136825;11441.713957151138;11572.768544628892;11601.883981160081;11005.944970275417;11106.50020749052;11238.943805280922;11257.943710281395;11275.943620281847;9786.804263914824;9876;9902.455364954709;9913.504324783877;9918.900810991798;6973.721051157897;7054.823629409306;7124;7187.209406965334;7227.313405226495;6978.441724662116;7038.3665470107935;7102.325279098477;7179.784606461814;7195.604241766544 11:49:38 INFO - PID 5757 | |4;prototype.html;498.50647858619965;508.32139094309093;533.7224643185541;535.617033820818;537.7176982084405;6359.554831161815;6395.168628078349;6420.133282006927;6439.361760605255;6493.155889734339;6092.299385570661;6215.62706237626;6277.278113017004;6283.057541368797;6294.496440284777;5050.292958985745;5068.859506611001;5093.949060509403;5122.385313762354;5125.2055931330515;4902.166631672606;5050.595952323823;5069.188929771238;5082.491750824906;5112.872178195556;5044.899102017957;5060.974695126519;5101;5106.412762532319;5111.412187598418;5029.949700503022;5030.84907452777;5035.823746168885;5060.367454068241;5078.974605126988;4499.0776890737325;4523.932141017887;4530.964253949862;4588;4589.678722489427;6269;6272.372762723737;6278.295805474791;6290.339514350973;6315.557910946236;5621.7751289948355;5682.829515114553;5691.800786972436;5799.333076696192;5799.3620701722775;6522.249941256769;6527.445167160797;6575.671216439207;6599.175103112111;6662.700178491979;4870.829520966749;4874.488178741217;4897.4857639948;4936.555709986083;4951.480094590052;6875.896861547081;6912.585244885323;6947.583145011265;7016.438684905246;7020.29797020299;5041.1430056889685;5078.4413714491375;5082.390113186441;5089.048351955857;5094.745262736848;5137.255098010767;5156.175011998137;5178.223266510054;5180.559652429511;5184.662996905189;5661.179129026268;5697.572682048863;5712.685802280831;5751.654900705971;5822.1266809978;6422.8715425691225;6520.380563846427;6531.497755516258;6621.900671489835;6662.966685166543;1704.3608646757466;1713.9657206855793;1787.499500139963;1794.5872449336725;1801.9459416217535;1075.3279200499687;1192.6284772511508;1194.343111288805;1203.9759204815855;1210.630757618917;5834.416558344132;5842.474177324061;5902.468777810018;5977.760889564369;5992.280926288873;6790.219124800612;6858.6570671466225;6879.828004299932;6891.27641597635;7039.683214255371;5940.138679891478;5956.463918247378;5992;6036.396360363929;6086.218092811847;3629.4011488104693;3638.6543278388513;3647.6534729200685;3661.212839239576;3669;6545.312742162099;6553.508486863504;6578.243501997236;6614.900776488356;6663.400293973566;7083.15002199729;7132.1441427029085;7136;7149.785506434815;7161.820954476179;6600.537962342686;6644.60132392058;6683.331666833375;6683.465322774166;6700.597964122168;6857.622830744357;6863.122335880212;6876.759313424006;6878.896816547655;6911.447084233349;6718.798436046926;6739.59562426256;6759.628220447923;6810.9318906810295;6856.200659538244;6459.580127291711;6475.967620161869;6505.739770409131;6547.574407663677;6610.933890661224;5008;5038.496150385079;5040.747962601782;5058.624289989207;5060.190369541003;6837.487188460786;6859.622720750406;6881.380675739208;6896.172459304916;6911.136107986502;6591.406773390418;6616.603003819594;6708.329167083252;6727.293634168537;6772.7629532968085;6653;6653.135092437952;6662.133922590032;6689.966550167218;6697.296783837822;6439.7424103035355;6524.706388212447;6564.146660934048;6578.5395022347975;6627.4698024158915;6139.9079013813935;6714.328567143247;6730.40879261478;6755.020522024377;6787.083743694736;6413.903791443226;6452.290248072614;6506.837329066811;6519.380658837403;6525.902111468236 11:49:38 INFO - PID 5757 | |5;yui.html;903.7605034665814;910.2535920545147;915.4690279637808;930.1489137439244;931.5668214280358;18761.71857422138;19834;19838.20647174113;19842.70235946462;20733.68899466509;19555.90222048888;20197.09113089911;20243.291484798036;20334.389968300973;20367.185312587517;960.812641534901;979.9265055120859;980.7106903463459;982.130814229405;987.3680844259679;920.5029284186531;986.5807032011367;994.1053052252994;998.7403275148441;1007.9798404031915;1045.9058684718375;1049.380865289479;1049.7795462952788;1049.821530339844;1055.1347894726327;1035.1770342577631;1042.3902017319876;1042.8070806900748;1043.5251960358057;1047.177965297238;768.0591275687294;773.3040263762603;773.8104164479738;776.351746291847;776.5418403142173;19601.313954011683;19942.70085948712;20051.598968020702;20155.294564690314;20202.898985505126;20517;20560.691589626167;21064.894675526677;21090.683639745264;21408.143674253166;15510.069395836173;15733.68532629341;15869.127198004106;15914.681706365924;15974.520764377086;3692.9815350923072;3711.832967516468;3718.4050551911837;3718.442233664972;3719.79541125238;123719;124385;124850;128274;129929;17080.57298567546;17316.134193290283;17545.035023073855;17668;17679.558011049827;30296.54555181674;30688.846555767526;30923.381532369225;31470.527942080887;31549.36901261962;7084.149902011792;7460.477766556398;8652.697155599524;9042.593083311267;9089.863652045224;4398.142362239332;4477;4489.012417268195;4493.932591011137;4509.594136527729;5458.754356053908;5480.780768769284;5540.889182216414;5560.082586373203;5584.748686309126;1078.4068762180768;1108.1854836695022;1113.7215696075982;1122.2985633978763;1125.0212315285753;665.1751827733575;679.9014142949245;680.119245576979;692.394155114275;692.4979389942251;11146.108311335074;11363.715907102223;11444.94277528607;11799.705007374712;12363.814542781865;703.4372501998381;709.606168576445;711.992880071203;719.5179229915968;721.5814827400095;5497.505224529811;6056.909146362808;6094.725737341831;6113.41922517369;6197.96901015492;108100.459497702;110070.44964775284;111280;112505.43747281375;115780.42109789397;87033.56483217671;93350.53324733333;94011.52994234985;97108.51445742726;99289;81403.18596814075;83107.5844620773;84533;85615.57192213999;86458.13541864627;10800.783984320113;10875.564977400816;10925;10960.12319014461;11309.660710178709;39911;40728.389074163315;41271.58728412678;41683.37474937818;43354.34968475538;51756.741216293674;52376.47623523716;52749;53301.4669853312;54225;15742.9212853935;16130.193490325672;18200.635987280446;18797.34209302668;18985.240590376783;21356.7864321361;21712.782872171076;21732.891335543853;21737.891310543346;22033.118675253452;18081.27674893042;19040.523986899883;19515.219391224193;19723.704144437557;19808.306709265682;18696.6260674783;18881.150348234638;19444.611107778048;19682.50793730168;19884.60230795405;19212;19394.127264273422;19503.317383891495;19523.51191220231;19650.410487685393;13101.148425352703;13996.16023038621;14280.42878284857;14737.705245895237;15128;12936.935315323362;14932;15450.22748862575;15682.451114210948;15738.606534836721 11:49:38 INFO - PID 5757 | __end_tp_report 11:49:38 INFO - PID 5757 | __start_cc_report 11:49:38 INFO - PID 5757 | _x_x_mozilla_cycle_collect,363 11:49:38 INFO - PID 5757 | __end_cc_report 11:49:38 INFO - PID 5757 | __startTimestamp1504723778891__endTimestamp 11:49:38 INFO - PID 5757 | 11:49:38 INFO - PID 5757 | ------- Summary: start ------- 11:49:38 INFO - PID 5757 | Number of tests: 6 11:49:38 INFO - PID 5757 | 11:49:38 INFO - PID 5757 | [#0] dojo.html Cycles:165 Average:6398.77 Median:6623.00 stddev:756.81 (11.4%) stddev-sans-first:717.86 11:49:38 INFO - PID 5757 | Values: 3256.6 3280.2 3280.7 3281.7 3315.7 6431.0 6477.8 6526.6 6528.4 6572.6 6297.5 6434.4 6444.8 6459.3 6470.8 5975.1 6022.6 6043.3 6053.8 6079.1 5772.6 6002.3 6041.9 6087.5 6149.5 5855.4 5946.2 5999.6 6040.2 6052.7 5914.5 5954.6 6021.5 6042.9 6045.5 5793.1 5846.3 5858.4 5888.2 5904.7 6442.2 6522.9 6546.3 6567.7 6578.5 6851.0 6925.6 6934.7 6935.7 6972.7 6932.8 6937.6 6945.1 7029.2 7029.9 6335.3 6457.7 6460.5 6505.0 6539.6 7187.1 7224.4 7260.3 7263.3 7283.0 6830.7 6843.1 6922.3 6938.1 6938.7 6800.1 6950.3 6988.2 7064.8 7067.5 6869.9 6904.2 6961.9 6991.3 7005.8 6814.2 6940.8 6986.5 7018.6 7019.9 5458.7 5463.9 5475.0 5490.8 5532.8 4803.7 4845.0 4860.7 4862.6 4867.1 6081.4 6082.9 6158.6 6161.3 6164.6 6797.4 6959.2 6991.7 6991.8 7093.4 5999.8 6024.9 6045.5 6058.0 6074.6 5982.2 5982.9 5989.1 5991.2 5992.0 6585.9 6636.3 6673.3 6675.6 6696.5 6965.3 6997.5 7004.4 7035.1 7054.3 6622.3 6650.6 6725.1 6727.2 6740.3 6680.6 6798.6 6837.7 6858.7 6886.5 6969.9 7019.6 7042.2 7053.1 7075.0 6717.4 6801.0 6801.2 6805.5 6810.4 6725.3 6727.0 6737.0 6812.7 6824.3 6503.1 6599.4 6611.8 6623.0 6630.6 6695.6 6709.4 6762.2 6769.5 6785.3 6540.3 6567.5 6632.2 6661.8 6674.2 11:49:38 INFO - PID 5757 | 11:49:38 INFO - PID 5757 | [#1] ext.html Cycles:180 Average:37123.65 Median:18265.29 stddev:53037.23 (290.4%) stddev-sans-first:53115.33 11:49:38 INFO - PID 5757 | Values: 656.4 741.5 750.7 751.1 761.1 30182.5 38770.8 39928.0 39935.4 39988.6 39764.6 39994.4 39994.8 40067.0 40117.8 7435.9 7486.4 7668.0 7697.3 7782.0 7569.8 7602.6 7734.1 7734.8 7735.4 7602.3 7679.7 7753.9 7759.4 7762.9 7684.9 7685.5 7686.6 7687.7 7687.7 7585.1 7660.5 7662.5 7662.6 7665.6 37144.6 37511.0 37563.8 37871.6 37899.2 3486.6 3529.0 3533.4 3546.9 3548.8 30390.8 30498.7 30517.5 30559.4 30663.7 5007.3 5147.8 5187.1 5205.2 5257.2 185287.0 186802.0 187352.0 187352.0 187931.0 47348.3 47449.3 47471.3 47608.3 47696.0 49732.3 49734.0 49918.5 49929.5 49975.5 12775.9 14845.1 14997.6 15061.5 15062.6 10466.4 12127.9 12337.5 12387.7 12394.4 12994.8 13129.0 13139.3 13159.6 13273.9 5379.3 5400.0 5407.4 5459.5 5507.6 1012.0 1020.9 1033.6 1045.1 1050.1 22285.0 22714.4 22781.8 22811.2 22871.9 1133.5 1136.8 1137.3 1138.7 1141.2 12007.5 12170.5 12178.8 12178.8 12222.3 222995.0 224219.0 224502.0 224537.0 225516.0 49999.3 50123.5 50161.2 50269.5 50363.2 1438.4 1462.8 1463.7 1467.8 1487.2 21808.0 22361.6 26231.5 26957.0 27284.0 45881.3 48338.8 48473.5 48473.8 48500.3 199297.0 201380.0 201391.0 201521.0 201598.0 29024.9 29784.6 31576.4 32105.4 32198.7 39960.0 40305.8 40309.6 40638.2 40775.4 29915.4 30074.0 30202.4 30773.2 30987.0 31026.1 32321.7 32437.7 32891.2 33044.0 36232.6 36294.8 36318.0 36595.5 36667.3 30616.9 31321.4 31869.5 32005.5 32540.2 23906.0 24020.4 24414.8 24574.9 24911.8 11:49:38 INFO - PID 5757 | 11:49:38 INFO - PID 5757 | [#2] jquery.html Cycles:180 Average:36405.07 Median:21970.40 stddev:85841.35 (390.7%) stddev-sans-first:86039.95 11:49:38 INFO - PID 5757 | Values: 552.3 555.6 567.8 570.8 580.2 21689.8 22208.6 22360.6 22429.9 22438.2 26443.0 27115.9 27265.9 27293.5 27301.0 6980.2 7213.9 7237.2 7251.1 7252.8 6670.7 6963.0 6994.8 6997.9 7034.1 6992.5 6997.4 7003.8 7007.3 7013.5 6967.4 6982.9 6989.3 6991.1 6996.2 6851.9 6858.2 6860.9 6861.3 6863.7 29471.9 30396.5 30530.8 30625.5 30628.2 2124.7 2147.2 2152.8 2157.5 2166.6 20554.0 21264.1 21316.7 21337.4 21337.8 2452.3 2464.4 2529.0 2578.2 2584.8 133066.3 135392.3 135961.3 135963.3 136031.3 19253.3 21866.0 21896.9 21936.6 22004.2 44246.8 44282.0 44440.3 44461.3 44525.3 10541.9 10577.6 10650.4 10739.8 10903.7 8260.9 8651.2 8681.1 8703.9 8761.4 10517.3 11201.6 11334.3 11396.1 11449.5 3520.7 3604.4 3723.4 3915.9 4183.3 951.8 975.1 975.3 976.9 1003.1 19322.9 19409.8 19410.0 19729.4 20179.5 1016.9 1030.6 1038.4 1046.8 1054.9 10605.7 10835.2 11040.4 11071.3 11214.3 500997.0 504732.0 516727.0 520995.0 523484.0 106677.0 107269.0 108004.0 111468.0 121717.4 1191.5 1209.1 1253.1 1254.4 1261.1 20706.2 20872.9 21024.2 21124.6 21384.8 57408.7 57468.0 58146.7 58614.4 61564.7 69290.0 69492.7 69626.3 69641.7 70195.6 12176.9 14386.2 15077.1 15135.6 15209.9 23141.9 23514.9 23548.8 23744.3 23907.2 18360.2 19114.5 19150.7 19157.8 19170.7 16769.7 16910.4 16960.3 17000.6 17339.2 19031.8 19267.2 19311.5 19454.8 19464.1 14620.6 14647.9 14736.0 14756.3 14812.6 18356.5 18719.3 18800.2 18889.4 18906.1 11:49:38 INFO - PID 5757 | 11:49:38 INFO - PID 5757 | [#3] mootools.html Cycles:180 Average:9508.04 Median:2702.91 stddev:6815.20 (252.1%) stddev-sans-first:6806.42 11:49:38 INFO - PID 5757 | Values: 1300.3 1360.6 1365.2 1370.3 1371.8 14920.9 15553.3 15559.7 15574.2 15962.0 15877.9 16132.1 16204.0 16215.6 16219.1 2706.2 2708.8 2752.1 2754.7 2756.3 2783.6 2847.4 2848.9 2849.0 2850.3 2709.6 2725.4 2738.8 2748.7 2765.8 2684.9 2689.4 2714.9 2719.8 2726.7 2659.7 2663.1 2687.7 2699.7 2706.1 14093.1 14323.8 14374.2 14487.7 14506.9 7438.7 7679.7 7693.2 7702.3 7725.7 5159.0 5222.0 5303.2 5396.9 5413.7 1915.4 1931.5 1936.5 1986.9 2000.9 26399.7 27098.6 27124.1 27137.5 27182.6 6843.7 6889.3 6929.8 7111.3 7120.3 20058.8 20066.7 20067.8 20110.0 20122.6 8018.4 8166.0 8170.0 8180.4 8183.7 7190.5 7210.7 7289.6 7329.0 7368.8 5678.8 5734.5 5828.0 5843.2 5865.2 1715.6 1783.5 1796.6 1798.7 1810.5 903.8 931.2 936.5 940.2 945.7 7598.6 7616.2 7701.3 7725.5 7732.4 994.7 1017.0 1021.9 1024.8 1026.6 5281.8 5358.4 5511.4 5564.5 5754.6 21637.5 21720.1 22042.2 22185.4 22216.0 19956.1 21282.4 21297.1 21352.6 21751.1 11137.5 11465.3 11526.9 11528.3 11528.9 8447.8 8756.3 9059.9 9156.7 9202.7 18025.0 18154.6 18533.0 18809.2 18903.5 18812.0 18935.7 19065.6 19271.1 19878.4 12081.3 12096.9 12346.2 12377.8 12379.3 13926.7 14086.7 14096.3 14097.7 14103.2 11274.5 11393.3 11441.7 11572.8 11601.9 11005.9 11106.5 11238.9 11257.9 11275.9 9786.8 9876.0 9902.5 9913.5 9918.9 6973.7 7054.8 7124.0 7187.2 7227.3 6978.4 7038.4 7102.3 7179.8 7195.6 11:49:38 INFO - PID 5757 | 11:49:38 INFO - PID 5757 | [#4] prototype.html Cycles:180 Average:5606.59 Median:6277.79 stddev:1583.32 (25.2%) stddev-sans-first:1540.64 11:49:38 INFO - PID 5757 | Values: 498.5 508.3 533.7 535.6 537.7 6359.6 6395.2 6420.1 6439.4 6493.2 6092.3 6215.6 6277.3 6283.1 6294.5 5050.3 5068.9 5093.9 5122.4 5125.2 4902.2 5050.6 5069.2 5082.5 5112.9 5044.9 5061.0 5101.0 5106.4 5111.4 5029.9 5030.8 5035.8 5060.4 5079.0 4499.1 4523.9 4531.0 4588.0 4589.7 6269.0 6272.4 6278.3 6290.3 6315.6 5621.8 5682.8 5691.8 5799.3 5799.4 6522.2 6527.4 6575.7 6599.2 6662.7 4870.8 4874.5 4897.5 4936.6 4951.5 6875.9 6912.6 6947.6 7016.4 7020.3 5041.1 5078.4 5082.4 5089.0 5094.7 5137.3 5156.2 5178.2 5180.6 5184.7 5661.2 5697.6 5712.7 5751.7 5822.1 6422.9 6520.4 6531.5 6621.9 6663.0 1704.4 1714.0 1787.5 1794.6 1801.9 1075.3 1192.6 1194.3 1204.0 1210.6 5834.4 5842.5 5902.5 5977.8 5992.3 6790.2 6858.7 6879.8 6891.3 7039.7 5940.1 5956.5 5992.0 6036.4 6086.2 3629.4 3638.7 3647.7 3661.2 3669.0 6545.3 6553.5 6578.2 6614.9 6663.4 7083.2 7132.1 7136.0 7149.8 7161.8 6600.5 6644.6 6683.3 6683.5 6700.6 6857.6 6863.1 6876.8 6878.9 6911.4 6718.8 6739.6 6759.6 6810.9 6856.2 6459.6 6476.0 6505.7 6547.6 6610.9 5008.0 5038.5 5040.7 5058.6 5060.2 6837.5 6859.6 6881.4 6896.2 6911.1 6591.4 6616.6 6708.3 6727.3 6772.8 6653.0 6653.1 6662.1 6690.0 6697.3 6439.7 6524.7 6564.1 6578.5 6627.5 6139.9 6714.3 6730.4 6755.0 6787.1 6413.9 6452.3 6506.8 6519.4 6525.9 11:49:38 INFO - PID 5757 | 11:49:38 INFO - PID 5757 | [#5] yui.html Cycles:180 Average:23356.77 Median:20538.85 stddev:31337.99 (152.6%) stddev-sans-first:31380.54 11:49:38 INFO - PID 5757 | Values: 903.8 910.3 915.5 930.1 931.6 18761.7 19834.0 19838.2 19842.7 20733.7 19555.9 20197.1 20243.3 20334.4 20367.2 960.8 979.9 980.7 982.1 987.4 920.5 986.6 994.1 998.7 1008.0 1045.9 1049.4 1049.8 1049.8 1055.1 1035.2 1042.4 1042.8 1043.5 1047.2 768.1 773.3 773.8 776.4 776.5 19601.3 19942.7 20051.6 20155.3 20202.9 20517.0 20560.7 21064.9 21090.7 21408.1 15510.1 15733.7 15869.1 15914.7 15974.5 3693.0 3711.8 3718.4 3718.4 3719.8 123719.0 124385.0 124850.0 128274.0 129929.0 17080.6 17316.1 17545.0 17668.0 17679.6 30296.5 30688.8 30923.4 31470.5 31549.4 7084.1 7460.5 8652.7 9042.6 9089.9 4398.1 4477.0 4489.0 4493.9 4509.6 5458.8 5480.8 5540.9 5560.1 5584.7 1078.4 1108.2 1113.7 1122.3 1125.0 665.2 679.9 680.1 692.4 692.5 11146.1 11363.7 11444.9 11799.7 12363.8 703.4 709.6 712.0 719.5 721.6 5497.5 6056.9 6094.7 6113.4 6198.0 108100.5 110070.4 111280.0 112505.4 115780.4 87033.6 93350.5 94011.5 97108.5 99289.0 81403.2 83107.6 84533.0 85615.6 86458.1 10800.8 10875.6 10925.0 10960.1 11309.7 39911.0 40728.4 41271.6 41683.4 43354.3 51756.7 52376.5 52749.0 53301.5 54225.0 15742.9 16130.2 18200.6 18797.3 18985.2 21356.8 21712.8 21732.9 21737.9 22033.1 18081.3 19040.5 19515.2 19723.7 19808.3 18696.6 18881.2 19444.6 19682.5 19884.6 19212.0 19394.1 19503.3 19523.5 19650.4 13101.1 13996.2 14280.4 14737.7 15128.0 12936.9 14932.0 15450.2 15682.5 15738.6 11:49:38 INFO - PID 5757 | -------- Summary: end -------- 11:49:38 INFO - PID 5757 | 11:49:39 INFO - TEST-INFO | 5757: exit 0 11:49:39 INFO - TEST-OK | dromaeo_css | took 1109871ms 11:49:39 INFO - TEST-START | kraken 11:49:39 INFO - Initialising browser for kraken test... 11:49:39 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49216/getInfo.html -profile /tmp/tmpts_Naw/profile 11:49:39 INFO - TEST-INFO | started process 6138 (/builds/slave/test/build/application/firefox/firefox http://localhost:49216/getInfo.html) 11:49:45 INFO - TEST-INFO | 6138: exit 0 11:49:45 INFO - Browser initialized. 11:49:45 INFO - Running cycle 1/1 for kraken test... 11:49:45 INFO - Using env: {'DISPLAY': ':0', 11:49:45 INFO - 'HOME': '/home/cltbld', 11:49:45 INFO - 'JSGC_DISABLE_POISONING': '1', 11:49:45 INFO - 'LANG': 'en_US.UTF-8', 11:49:45 INFO - 'LANGUAGE': 'en_US:en', 11:49:45 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:49:45 INFO - 'LOGNAME': 'cltbld', 11:49:45 INFO - 'MAIL': '/var/mail/cltbld', 11:49:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:49:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:49:45 INFO - 'MOZ_CRASHREPORTER': '1', 11:49:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:49:45 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:49:45 INFO - 'MOZ_NO_REMOTE': '1', 11:49:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:49:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:49:45 INFO - 'NO_EM_RESTART': '1', 11:49:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:49:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:49:45 INFO - 'PWD': '/builds/slave/test', 11:49:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:49:45 INFO - 'RUST_BACKTRACE': 'full', 11:49:45 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:49:45 INFO - 'SHELL': '/bin/bash', 11:49:45 INFO - 'SHLVL': '1', 11:49:45 INFO - 'TERM': 'linux', 11:49:45 INFO - 'TMOUT': '86400', 11:49:45 INFO - 'USER': 'cltbld', 11:49:45 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504722597.63712-1800445697', 11:49:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:49:45 INFO - '_': '/tools/buildbot/bin/python'} 11:49:45 INFO - TEST-INFO | started process 6339 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpts_Naw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:49:46 INFO - PID 6339 | 11:49:46 INFO - PID 6339 | (/builds/slave/test/build/application/firefox/firefox:6391): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:49:46 INFO - PID 6339 | 11:49:56 INFO - PID 6339 | RSS: Main: 170270720 11:49:56 INFO - PID 6339 | 11:49:59 INFO - PID 6339 | Cycle 1(1): loaded http://localhost:49216/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49216/tests/kraken/driver.html?testName=audio-beat-detection) 11:50:00 INFO - PID 6339 | RSS: Main: 176467968 11:50:00 INFO - PID 6339 | 11:50:03 INFO - PID 6339 | Cycle 1(1): loaded http://localhost:49216/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49216/tests/kraken/driver.html?testName=audio-dft) 11:50:03 INFO - PID 6339 | RSS: Main: 177582080 11:50:03 INFO - PID 6339 | 11:50:06 INFO - PID 6339 | Cycle 1(1): loaded http://localhost:49216/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49216/tests/kraken/driver.html?testName=audio-fft) 11:50:07 INFO - PID 6339 | RSS: Main: 171311104 11:50:07 INFO - PID 6339 | 11:50:09 INFO - PID 6339 | Cycle 1(1): loaded http://localhost:49216/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49216/tests/kraken/driver.html?testName=audio-oscillator) 11:50:10 INFO - PID 6339 | RSS: Main: 172445696 11:50:10 INFO - PID 6339 | 11:50:12 INFO - PID 6339 | Cycle 1(1): loaded http://localhost:49216/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49216/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:50:12 INFO - PID 6339 | RSS: Main: 172752896 11:50:12 INFO - PID 6339 | 11:50:15 INFO - PID 6339 | Cycle 1(1): loaded http://localhost:49216/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49216/tests/kraken/driver.html?testName=imaging-darkroom) 11:50:16 INFO - PID 6339 | RSS: Main: 175419392 11:50:16 INFO - PID 6339 | 11:50:20 INFO - PID 6339 | Cycle 1(1): loaded http://localhost:49216/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49216/tests/kraken/driver.html?testName=imaging-desaturate) 11:50:20 INFO - PID 6339 | RSS: Main: 175185920 11:50:20 INFO - PID 6339 | 11:50:23 INFO - PID 6339 | Cycle 1(1): loaded http://localhost:49216/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49216/tests/kraken/driver.html?testName=json-parse-financial) 11:50:24 INFO - PID 6339 | RSS: Main: 175640576 11:50:24 INFO - PID 6339 | 11:50:25 INFO - PID 6339 | Cycle 1(1): loaded http://localhost:49216/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49216/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:50:26 INFO - PID 6339 | RSS: Main: 176226304 11:50:26 INFO - PID 6339 | 11:50:28 INFO - PID 6339 | Cycle 1(1): loaded http://localhost:49216/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49216/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:50:28 INFO - PID 6339 | RSS: Main: 175960064 11:50:28 INFO - PID 6339 | 11:50:30 INFO - PID 6339 | Cycle 1(1): loaded http://localhost:49216/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49216/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:50:31 INFO - PID 6339 | RSS: Main: 172699648 11:50:31 INFO - PID 6339 | 11:50:34 INFO - PID 6339 | Cycle 1(1): loaded http://localhost:49216/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49216/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:50:34 INFO - PID 6339 | RSS: Main: 173314048 11:50:34 INFO - PID 6339 | 11:50:37 INFO - PID 6339 | Cycle 1(1): loaded http://localhost:49216/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49216/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:50:38 INFO - PID 6339 | RSS: Main: 173158400 11:50:38 INFO - PID 6339 | 11:50:40 INFO - PID 6339 | Cycle 1(1): loaded http://localhost:49216/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49216/tests/kraken/driver.html?testName=ai-astar) 11:50:40 INFO - PID 6339 | RSS: Main: 174940160 11:50:40 INFO - PID 6339 | 11:50:40 INFO - PID 6339 | __start_tp_report 11:50:40 INFO - PID 6339 | _x_x_mozilla_page_load 11:50:40 INFO - PID 6339 | _x_x_mozilla_page_load_details 11:50:40 INFO - PID 6339 | |i|pagename|runs| 11:50:40 INFO - PID 6339 | |0;ai-astar;98;111;103;107;107;107;110;99;109;108 11:50:40 INFO - PID 6339 | |1;audio-beat-detection;142;118;140;169;117;147;145;121;172;117 11:50:40 INFO - PID 6339 | |2;audio-dft;162;161;161;162;160;162;161;167;169;163 11:50:40 INFO - PID 6339 | |3;audio-fft;73;74;73;179;75;74;75;73;175;75 11:50:40 INFO - PID 6339 | |4;audio-oscillator;79;83;134;80;81;81;130;79;80;123 11:50:40 INFO - PID 6339 | |5;imaging-gaussian-blur;114;114;115;114;113;114;114;114;114;114 11:50:40 INFO - PID 6339 | |6;imaging-darkroom;160;148;148;148;149;148;148;148;149;148 11:50:40 INFO - PID 6339 | |7;imaging-desaturate;98;94;94;94;94;93;95;95;94;96 11:50:40 INFO - PID 6339 | |8;json-parse-financial;53;54;54;53;52;52;52;52;52;53 11:50:40 INFO - PID 6339 | |9;json-stringify-tinderbox;38;38;38;38;37;39;38;43;38;38 11:50:40 INFO - PID 6339 | |10;stanford-crypto-aes;74;73;75;74;74;74;75;74;78;76 11:50:40 INFO - PID 6339 | |11;stanford-crypto-ccm;118;119;121;120;123;119;115;119;142;170 11:50:40 INFO - PID 6339 | |12;stanford-crypto-pbkdf2;171;173;183;173;167;177;183;171;175;168 11:50:40 INFO - PID 6339 | |13;stanford-crypto-sha256-iterative;66;66;66;64;63;70;72;71;65;65 11:50:40 INFO - PID 6339 | __end_tp_report 11:50:40 INFO - PID 6339 | __start_cc_report 11:50:40 INFO - PID 6339 | _x_x_mozilla_cycle_collect,942 11:50:40 INFO - PID 6339 | __end_cc_report 11:50:40 INFO - PID 6339 | __startTimestamp1504723840492__endTimestamp 11:50:40 INFO - PID 6339 | 11:50:40 INFO - PID 6339 | ------- Summary: start ------- 11:50:40 INFO - PID 6339 | Number of tests: 14 11:50:40 INFO - PID 6339 | 11:50:40 INFO - PID 6339 | [#0] ai-astar Cycles:10 Average:105.90 Median:109.50 stddev:4.46 (4.1%) stddev-sans-first:3.70 11:50:40 INFO - PID 6339 | Values: 98.0 111.0 103.0 107.0 107.0 107.0 110.0 99.0 109.0 108.0 11:50:40 INFO - PID 6339 | 11:50:40 INFO - PID 6339 | [#1] audio-beat-detection Cycles:10 Average:138.80 Median:143.50 stddev:20.63 (14.4%) stddev-sans-first:21.85 11:50:40 INFO - PID 6339 | Values: 142.0 118.0 140.0 169.0 117.0 147.0 145.0 121.0 172.0 117.0 11:50:40 INFO - PID 6339 | 11:50:40 INFO - PID 6339 | [#2] audio-dft Cycles:10 Average:162.80 Median:162.00 stddev:2.90 (1.8%) stddev-sans-first:3.06 11:50:40 INFO - PID 6339 | Values: 162.0 161.0 161.0 162.0 160.0 162.0 161.0 167.0 169.0 163.0 11:50:40 INFO - PID 6339 | 11:50:40 INFO - PID 6339 | [#3] audio-fft Cycles:10 Average:94.60 Median:74.00 stddev:43.45 (58.7%) stddev-sans-first:45.37 11:50:40 INFO - PID 6339 | Values: 73.0 74.0 73.0 179.0 75.0 74.0 75.0 73.0 175.0 75.0 11:50:40 INFO - PID 6339 | 11:50:40 INFO - PID 6339 | [#4] audio-oscillator Cycles:10 Average:95.00 Median:80.00 stddev:23.64 (29.5%) stddev-sans-first:24.35 11:50:40 INFO - PID 6339 | Values: 79.0 83.0 134.0 80.0 81.0 81.0 130.0 79.0 80.0 123.0 11:50:40 INFO - PID 6339 | 11:50:40 INFO - PID 6339 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 11:50:40 INFO - PID 6339 | Values: 114.0 114.0 115.0 114.0 113.0 114.0 114.0 114.0 114.0 114.0 11:50:40 INFO - PID 6339 | 11:50:40 INFO - PID 6339 | [#6] imaging-darkroom Cycles:10 Average:149.40 Median:148.00 stddev:3.75 (2.5%) stddev-sans-first:0.44 11:50:40 INFO - PID 6339 | Values: 160.0 148.0 148.0 148.0 149.0 148.0 148.0 148.0 149.0 148.0 11:50:40 INFO - PID 6339 | 11:50:40 INFO - PID 6339 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:94.50 stddev:1.42 (1.5%) stddev-sans-first:0.87 11:50:40 INFO - PID 6339 | Values: 98.0 94.0 94.0 94.0 94.0 93.0 95.0 95.0 94.0 96.0 11:50:40 INFO - PID 6339 | 11:50:40 INFO - PID 6339 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:53.00 stddev:0.82 (1.6%) stddev-sans-first:0.87 11:50:40 INFO - PID 6339 | Values: 53.0 54.0 54.0 53.0 52.0 52.0 52.0 52.0 52.0 53.0 11:50:40 INFO - PID 6339 | 11:50:40 INFO - PID 6339 | [#9] json-stringify-tinderbox Cycles:10 Average:38.50 Median:38.00 stddev:1.65 (4.3%) stddev-sans-first:1.74 11:50:40 INFO - PID 6339 | Values: 38.0 38.0 38.0 38.0 37.0 39.0 38.0 43.0 38.0 38.0 11:50:40 INFO - PID 6339 | 11:50:40 INFO - PID 6339 | [#10] stanford-crypto-aes Cycles:10 Average:74.70 Median:74.50 stddev:1.42 (1.9%) stddev-sans-first:1.48 11:50:40 INFO - PID 6339 | Values: 74.0 73.0 75.0 74.0 74.0 74.0 75.0 74.0 78.0 76.0 11:50:40 INFO - PID 6339 | 11:50:40 INFO - PID 6339 | [#11] stanford-crypto-ccm Cycles:10 Average:126.60 Median:120.50 stddev:16.97 (14.1%) stddev-sans-first:17.71 11:50:40 INFO - PID 6339 | Values: 118.0 119.0 121.0 120.0 123.0 119.0 115.0 119.0 142.0 170.0 11:50:40 INFO - PID 6339 | 11:50:40 INFO - PID 6339 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.10 Median:174.00 stddev:5.55 (3.2%) stddev-sans-first:5.77 11:50:40 INFO - PID 6339 | Values: 171.0 173.0 183.0 173.0 167.0 177.0 183.0 171.0 175.0 168.0 11:50:40 INFO - PID 6339 | 11:50:40 INFO - PID 6339 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:66.00 stddev:3.08 (4.7%) stddev-sans-first:3.26 11:50:40 INFO - PID 6339 | Values: 66.0 66.0 66.0 64.0 63.0 70.0 72.0 71.0 65.0 65.0 11:50:40 INFO - PID 6339 | -------- Summary: end -------- 11:50:40 INFO - PID 6339 | 11:50:40 INFO - PID 6339 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:50:41 INFO - TEST-INFO | 6339: exit 0 11:50:41 INFO - TEST-OK | kraken | took 61606ms 11:50:41 INFO - SUITE-END | took 1171s 11:50:41 INFO - Completed test suite (00:19:31) 11:50:41 INFO - javascript benchmark 11:50:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8725.40106540693, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3256.5929258842643, 3280.1799550112473, 3280.7211387032103, 3281.7374610031193, 3315.7347412207027, 6430.967845160773, 6477.838054048651, 6526.608403495794, 6528.379803918626, 6572.6056436613835, 6297.464715499188, 6434.420902118808, 6444.838879028027, 6459.321771214014, 6470.805875823721, 5975.133605627181, 6022.638641681502, 6043.305019922699, 6053.788117415891, 6079.148919151322, 5772.5670574706855, 6002.279726432834, 6041.879162416749, 6087.4825639820665, 6149.477294429978, 5855.385184555625, 5946.1675365448655, 5999.640021598696, 6040.214772079624, 6052.727627256784, 5914.526837852962, 5954.583179177459, 6021.458068773832, 6042.9395706042815, 6045.546584006217, 5793.13103034544, 5846.298444186725, 5858.414158584107, 5888.175655408247, 5904.704764761787, 6442.226932768098, 6522.902156467657, 6546.312637173076, 6567.67161641916, 6578.506612004119, 6851.0, 6925.58446493212, 6934.682410342019, 6935.72257109715, 6972.651367431608, 6932.792016239521, 6937.583744975267, 6945.062416573799, 7029.156501219886, 7029.929700702979, 6335.334789847046, 6457.741690332381, 6460.450861676763, 6504.9674751625935, 6539.6403197824575, 7187.065681461481, 7224.422046236288, 7260.310270524292, 7263.34629883313, 7282.963585182146, 6830.658467076626, 6843.144606924135, 6922.307769223037, 6938.132733408324, 6938.653067346612, 6800.08198893153, 6950.339717726808, 6988.19635741886, 7064.788056358318, 7067.5052746307265, 6869.862602748017, 6904.171499420001, 6961.860762784716, 6991.265917078736, 7005.754798582026, 6814.182298124257, 6940.757073502504, 6986.545874518241, 7018.649067546602, 7019.92980070203, 5458.6724796512335, 5463.945360546423, 5475.0418676731415, 5490.780368785204, 5532.7786888524815, 4803.735794531334, 4844.975775121102, 4860.708357498562, 4862.586680132157, 4867.123917694778, 6081.3614570470345, 6082.878342433127, 6158.568900177034, 6161.291451483047, 6164.599301045418, 6797.354251346114, 6959.164900212007, 6991.685374158175, 6991.7902462926195, 7093.361597456254, 5999.820005399845, 6024.879502409927, 6045.486133678599, 6058.030715085565, 6074.574779765462, 5982.192404025401, 5982.850428739316, 5989.071693887455, 5991.191189189491, 5992.041273396236, 6585.9012114818315, 6636.336366363422, 6673.299303573152, 6675.566088204251, 6696.46428285736, 6965.303469652994, 6997.475189360818, 7004.3696067354185, 7035.05026821393, 7054.329838665319, 6622.304658011032, 6650.6342151180215, 6725.125733654593, 6727.226368967631, 6740.258571557027, 6680.5991640501725, 6798.558093723892, 6837.692303846438, 6858.725650974105, 6886.51794374389, 6969.895451568129, 7019.5788252705, 7042.154941407064, 7053.0830991970715, 7075.044868942627, 6717.395434410926, 6800.965995169992, 6801.18385793708, 6805.455563554805, 6810.421114205348, 6725.327467253235, 6726.966365168143, 6736.966315168393, 6812.693428795617, 6824.317568243136, 6503.122078519339, 6599.439047681001, 6611.768588099393, 6623.0, 6630.569013014138, 6695.564788288746, 6709.429698475684, 6762.222344430454, 6769.492288078315, 6785.321467853372, 6540.345965403421, 6567.507436942153, 6632.2372927114, 6661.833454163685, 6674.232463266787], "value": 6341.8932011786455, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [656.3600489522713, 741.5365396627108, 750.6659536506257, 751.1324420294554, 761.136110514566, 30182.547261791064, 38770.80614596927, 39927.99999999993, 39935.40096898545, 39988.60011399885, 39764.60235397645, 39994.400223991004, 39994.8000259999, 40067.0, 40117.7994110029, 7435.85128297435, 7486.401087912981, 7668.041494813148, 7697.268759467842, 7781.96109019457, 7569.810754731148, 7602.581857997808, 7734.1105772836, 7734.806629834243, 7735.419843511731, 7602.277783610548, 7679.6928122875015, 7753.883691744629, 7759.379249660042, 7762.883556746625, 7684.884726729104, 7685.538867667958, 7686.577238251922, 7687.692492300302, 7687.730929417499, 7585.089789225273, 7660.502067365604, 7662.463627546074, 7662.616869156575, 7665.5783931883725, 37144.628553714385, 37511.0, 37563.81218093892, 37871.621283787084, 37899.24201515954, 3486.581610206766, 3529.047157267549, 3533.3639944809925, 3546.929061418783, 3548.787072775616, 30390.848045759853, 30498.695013049808, 30517.542236866466, 30559.38881222363, 30663.69336306653, 5007.349044624212, 5147.794088236466, 5187.118189907687, 5205.193195054772, 5257.158854583248, 185287.0, 186802.0, 187352.0, 187352.0, 187931.0, 47348.28977565339, 47449.28826067612, 47471.287930681065, 47608.285875711896, 47696.0, 49732.254016189785, 49734.0, 49918.500814991385, 49929.50070499321, 49975.50024499781, 12775.936120319524, 14845.109293442429, 14997.550073497594, 15061.548153555412, 15062.623434414227, 10466.424346661006, 12127.878721212852, 12337.50649974009, 12387.690307742198, 12394.380280986095, 12994.805077923838, 13129.0, 13139.277339746406, 13159.60521184366, 13273.933630331787, 5379.266245703756, 5400.0, 5407.432219616962, 5459.508644222039, 5507.614466987353, 1011.9747006324901, 1020.8519764634235, 1033.6382266206767, 1045.1429827541342, 1050.1021626509225, 22285.0, 22714.43213919632, 22781.772182277964, 22811.201607943643, 22871.88564057202, 1133.484264659578, 1136.8181091025397, 1137.3062431916524, 1138.7494751154732, 1141.2467771271088, 12007.519699211934, 12170.452329645188, 12178.756424871453, 12178.75642487163, 12222.327771972448, 222995.0, 224219.0, 224502.0, 224537.0, 225516.0, 49999.25001124913, 50123.49876501261, 50161.24758128631, 50269.49730502722, 50363.24455133176, 1438.4246301479284, 1462.8464011278663, 1463.743844827151, 1467.7798330250548, 1487.152323175801, 21808.0, 22361.552768944854, 26231.475370492866, 26956.982369168316, 27284.0, 45881.311780322656, 48338.75830620824, 48473.5152648469, 48473.75763121162, 48500.272495911886, 199297.0, 201380.0, 201391.0, 201521.0, 201598.0, 29024.854875725487, 29784.55323170111, 31576.36847263088, 32105.35789284248, 32198.678013220506, 39960.0, 40305.79847100746, 40309.596904030586, 40638.18723625452, 40775.38836917508, 29915.401691966475, 30074.0, 30202.395952080395, 30773.23066923255, 30987.0, 31026.069217923497, 32321.676783232808, 32437.67562324441, 32891.17772055718, 33044.0, 36232.63767362398, 36294.8185259072, 36318.0, 36595.45106823453, 36667.26665466622, 30616.91621853972, 31321.37357252888, 31869.521957171124, 32005.519917200796, 32540.186495337806, 23906.0, 24020.39949001289, 24414.75585244196, 24574.877125614257, 24911.75088249167], "value": 16868.033200986152, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.2930648769574, 555.5749851363707, 567.8012695556556, 570.7517230004945, 580.1790466489917, 21689.783102168974, 22208.55582888341, 22360.552788944213, 22429.887850560746, 22438.214662486796, 26443.0, 27115.864420677917, 27265.863670681618, 27293.45413091737, 27301.0, 6980.19727731311, 7213.855722885539, 7237.240089790575, 7251.057362542889, 7252.818679533001, 6670.7331706731675, 6963.0, 6994.755183568576, 6997.860042799141, 7034.05040319558, 6992.5105242633035, 6997.44020478363, 7003.7548685795755, 7007.299270073003, 7013.544119632207, 6967.407770339527, 6982.89525657113, 6989.301069893047, 6991.056207412011, 6996.1604607446925, 6851.931480685179, 6858.245592984767, 6860.862782744367, 6861.279565645636, 6863.691133898937, 29471.852640736874, 30396.54405183924, 30530.84734576313, 30625.540616890765, 30628.234294142825, 2124.6812978053417, 2147.2162660628915, 2152.827773778094, 2157.471419502216, 2166.566686662674, 20554.0, 21264.14943402262, 21316.68024979611, 21337.35987920365, 21337.786622133735, 2452.288836237489, 2464.445499762557, 2528.9873550632315, 2578.2136448383235, 2584.831985920909, 133066.33466832605, 135392.32303838516, 135961.32019339938, 135963.32018339945, 136031.31984340114, 19253.32613358526, 21866.0, 21896.89051554732, 21936.561268774854, 22004.229851955104, 44246.77876610596, 44282.0, 44440.3333949991, 44461.333080003824, 44525.332120018225, 10541.947290263499, 10577.57689692419, 10650.414227217423, 10739.83890241647, 10903.727406814734, 8260.876086858701, 8651.178138076873, 8681.131886811268, 8703.911061345232, 8761.430507017023, 10517.316374435637, 11201.60794372193, 11334.319940803578, 11396.088312934946, 11449.542018319175, 3520.735944804099, 3604.4232922732235, 3723.422869455239, 3915.8629447969756, 4183.330667093251, 951.7668171298076, 975.1467465967279, 975.2539307429822, 976.8947917238363, 1003.0821798054844, 19322.903385483263, 19409.8059019408, 19410.02949852502, 19729.40811775649, 20179.495512612302, 1016.8932262112372, 1030.5929157982705, 1038.4081073787868, 1046.7906418716286, 1054.8681414823147, 10605.73485662849, 10835.18736094796, 11040.447977601088, 11071.280366776295, 11214.31853139169, 500997.0, 504732.0, 516727.0, 520995.0, 523484.0, 106677.0, 107269.0, 108004.0, 111468.0, 121717.39141304414, 1191.4817054581285, 1209.0690168570152, 1253.1415980053876, 1254.3978890132787, 1261.0920137500984, 20706.171753129707, 20872.895635521727, 21024.159033638483, 21124.577508449438, 21384.78615213828, 57408.71295643495, 57468.0, 58146.70926645509, 58614.4138558609, 61564.69217653883, 69290.0, 69492.65253673699, 69626.30373696199, 69641.65179174072, 70195.64902175457, 12176.939115304722, 14386.208758518382, 15077.095374277578, 15135.621609459411, 15209.923950380176, 23141.88429057844, 23514.882425587763, 23548.764512354657, 23744.287671369886, 23907.16324928619, 18360.173792179117, 19114.52213694669, 19150.71273930864, 19157.8084219156, 19170.71243931314, 16769.661939041944, 16910.40813571519, 16960.321587136375, 17000.57498562546, 17339.2197351122, 19031.809681903003, 19267.22931082741, 19311.517212069248, 19454.805451945864, 19464.1241144146, 14620.634484137981, 14647.853521465075, 14736.042157259744, 14756.262186890826, 14812.62968425798, 18356.541086472946, 18719.344822931133, 18800.15399307007, 18889.433317000512, 18906.14922328526], "value": 12818.717619859492, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1300.3108352573136, 1360.625827897328, 1365.1536047650452, 1370.300826974552, 1371.7873729571916, 14920.850791492096, 15553.300101495432, 15559.688806223883, 15574.221288935536, 15962.0, 15877.920610396932, 16132.112733799637, 16204.0, 16215.594610134753, 16219.107949062796, 2706.161090062082, 2708.8103832731717, 2752.1055656911485, 2754.6832114306812, 2756.2558109310476, 2783.554631258999, 2847.373577812878, 2848.857557122146, 2848.957265641007, 2850.2589326774983, 2709.607106969488, 2725.441284536665, 2738.8219765715266, 2748.69764325925, 2765.7925655575814, 2684.8657567121663, 2689.4217743185213, 2714.9321266968286, 2719.809613327068, 2726.6864310604337, 2659.734026597344, 2663.1211700138906, 2687.7177896320995, 2699.7030326664053, 2706.120510833987, 14093.083949543246, 14323.78514322286, 14374.20941848198, 14487.710245795026, 14506.927465362605, 7438.702451901918, 7679.654415551314, 7693.19221481742, 7702.268284512962, 7725.652345644459, 5158.97420512895, 5221.973890130525, 5303.151495760699, 5396.892062158735, 5413.675179489243, 1915.3775023117541, 1931.51712071982, 1936.4674714453497, 1986.9006549672458, 2000.9299674511467, 26399.73600263992, 27098.5935210972, 27124.050658226868, 27137.457250855266, 27182.5922611161, 6843.726250949906, 6889.311068893071, 6929.757458488929, 7111.324424179798, 7120.287971202838, 20058.799412005985, 20066.698999515018, 20067.799322006886, 20109.994500274926, 20122.597548048958, 8018.358531317481, 8165.95917020423, 8170.0, 8180.386471014579, 8183.71357002502, 7190.460715446363, 7210.747623833141, 7289.599072050982, 7329.047223860864, 7368.815779605446, 5678.829635110953, 5734.541236701054, 5828.038373668297, 5843.152742852262, 5865.178874957509, 1715.5796829776784, 1783.4649605118414, 1796.6047469556677, 1798.6762382771226, 1810.4568629411124, 903.7785742493131, 931.189864817613, 936.4615346175976, 940.2102234123232, 945.6690158444627, 7598.620068996528, 7616.238376162451, 7701.268379503938, 7725.5364678119495, 7732.381409487228, 994.7065615643403, 1016.9644062457778, 1021.918246540275, 1024.8462730590322, 1026.630413051301, 5281.762320695578, 5358.437364076716, 5511.4213007633625, 5564.527015203672, 5754.625949313281, 21637.459063523536, 21720.131194752033, 22042.228522001653, 22185.44536386603, 22216.0, 19956.10197541114, 21282.36152915415, 21297.148114075262, 21352.572948540943, 21751.12995480195, 11137.498812553455, 11465.254758440842, 11526.88473115275, 11528.250663706833, 11528.942355288338, 8447.831043378976, 8756.343274254328, 9059.909400905906, 9156.6795162169, 9202.677906273248, 18025.0, 18154.636907262044, 18533.0, 18809.247630094644, 18903.527411814815, 18812.0, 18935.715964260824, 19065.618687626447, 19271.1327990238, 19878.403647890587, 12081.335526546125, 12096.879031209575, 12346.197497162655, 12377.752444951231, 12379.257244564995, 13926.651833704238, 14086.718265634834, 14096.295185240902, 14097.718045638825, 14103.153810771386, 11274.492647831032, 11393.259438136825, 11441.713957151138, 11572.768544628892, 11601.883981160081, 11005.944970275417, 11106.50020749052, 11238.943805280922, 11257.943710281395, 11275.943620281847, 9786.804263914824, 9876.0, 9902.455364954709, 9913.504324783877, 9918.900810991798, 6973.721051157897, 7054.823629409306, 7124.0, 7187.209406965334, 7227.313405226495, 6978.441724662116, 7038.3665470107935, 7102.325279098477, 7179.784606461814, 7195.604241766544], "value": 6816.339478284508, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.50647858619965, 508.32139094309093, 533.7224643185541, 535.617033820818, 537.7176982084405, 6359.554831161815, 6395.168628078349, 6420.133282006927, 6439.361760605255, 6493.155889734339, 6092.299385570661, 6215.62706237626, 6277.278113017004, 6283.057541368797, 6294.496440284777, 5050.292958985745, 5068.859506611001, 5093.949060509403, 5122.385313762354, 5125.2055931330515, 4902.166631672606, 5050.595952323823, 5069.188929771238, 5082.491750824906, 5112.872178195556, 5044.899102017957, 5060.974695126519, 5101.0, 5106.412762532319, 5111.412187598418, 5029.949700503022, 5030.84907452777, 5035.823746168885, 5060.367454068241, 5078.974605126988, 4499.0776890737325, 4523.932141017887, 4530.964253949862, 4588.0, 4589.678722489427, 6269.0, 6272.372762723737, 6278.295805474791, 6290.339514350973, 6315.557910946236, 5621.7751289948355, 5682.829515114553, 5691.800786972436, 5799.333076696192, 5799.3620701722775, 6522.249941256769, 6527.445167160797, 6575.671216439207, 6599.175103112111, 6662.700178491979, 4870.829520966749, 4874.488178741217, 4897.4857639948, 4936.555709986083, 4951.480094590052, 6875.896861547081, 6912.585244885323, 6947.583145011265, 7016.438684905246, 7020.29797020299, 5041.1430056889685, 5078.4413714491375, 5082.390113186441, 5089.048351955857, 5094.745262736848, 5137.255098010767, 5156.175011998137, 5178.223266510054, 5180.559652429511, 5184.662996905189, 5661.179129026268, 5697.572682048863, 5712.685802280831, 5751.654900705971, 5822.1266809978, 6422.8715425691225, 6520.380563846427, 6531.497755516258, 6621.900671489835, 6662.966685166543, 1704.3608646757466, 1713.9657206855793, 1787.499500139963, 1794.5872449336725, 1801.9459416217535, 1075.3279200499687, 1192.6284772511508, 1194.343111288805, 1203.9759204815855, 1210.630757618917, 5834.416558344132, 5842.474177324061, 5902.468777810018, 5977.760889564369, 5992.280926288873, 6790.219124800612, 6858.6570671466225, 6879.828004299932, 6891.27641597635, 7039.683214255371, 5940.138679891478, 5956.463918247378, 5992.0, 6036.396360363929, 6086.218092811847, 3629.4011488104693, 3638.6543278388513, 3647.6534729200685, 3661.212839239576, 3669.0, 6545.312742162099, 6553.508486863504, 6578.243501997236, 6614.900776488356, 6663.400293973566, 7083.15002199729, 7132.1441427029085, 7136.0, 7149.785506434815, 7161.820954476179, 6600.537962342686, 6644.60132392058, 6683.331666833375, 6683.465322774166, 6700.597964122168, 6857.622830744357, 6863.122335880212, 6876.759313424006, 6878.896816547655, 6911.447084233349, 6718.798436046926, 6739.59562426256, 6759.628220447923, 6810.9318906810295, 6856.200659538244, 6459.580127291711, 6475.967620161869, 6505.739770409131, 6547.574407663677, 6610.933890661224, 5008.0, 5038.496150385079, 5040.747962601782, 5058.624289989207, 5060.190369541003, 6837.487188460786, 6859.622720750406, 6881.380675739208, 6896.172459304916, 6911.136107986502, 6591.406773390418, 6616.603003819594, 6708.329167083252, 6727.293634168537, 6772.7629532968085, 6653.0, 6653.135092437952, 6662.133922590032, 6689.966550167218, 6697.296783837822, 6439.7424103035355, 6524.706388212447, 6564.146660934048, 6578.5395022347975, 6627.4698024158915, 6139.9079013813935, 6714.328567143247, 6730.40879261478, 6755.020522024377, 6787.083743694736, 6413.903791443226, 6452.290248072614, 6506.837329066811, 6519.380658837403, 6525.902111468236], "value": 5135.548486442177, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [903.7605034665814, 910.2535920545147, 915.4690279637808, 930.1489137439244, 931.5668214280358, 18761.71857422138, 19834.0, 19838.20647174113, 19842.70235946462, 20733.68899466509, 19555.90222048888, 20197.09113089911, 20243.291484798036, 20334.389968300973, 20367.185312587517, 960.812641534901, 979.9265055120859, 980.7106903463459, 982.130814229405, 987.3680844259679, 920.5029284186531, 986.5807032011367, 994.1053052252994, 998.7403275148441, 1007.9798404031915, 1045.9058684718375, 1049.380865289479, 1049.7795462952788, 1049.821530339844, 1055.1347894726327, 1035.1770342577631, 1042.3902017319876, 1042.8070806900748, 1043.5251960358057, 1047.177965297238, 768.0591275687294, 773.3040263762603, 773.8104164479738, 776.351746291847, 776.5418403142173, 19601.313954011683, 19942.70085948712, 20051.598968020702, 20155.294564690314, 20202.898985505126, 20517.0, 20560.691589626167, 21064.894675526677, 21090.683639745264, 21408.143674253166, 15510.069395836173, 15733.68532629341, 15869.127198004106, 15914.681706365924, 15974.520764377086, 3692.9815350923072, 3711.832967516468, 3718.4050551911837, 3718.442233664972, 3719.79541125238, 123719.0, 124385.0, 124850.0, 128274.0, 129929.0, 17080.57298567546, 17316.134193290283, 17545.035023073855, 17668.0, 17679.558011049827, 30296.54555181674, 30688.846555767526, 30923.381532369225, 31470.527942080887, 31549.36901261962, 7084.149902011792, 7460.477766556398, 8652.697155599524, 9042.593083311267, 9089.863652045224, 4398.142362239332, 4477.0, 4489.012417268195, 4493.932591011137, 4509.594136527729, 5458.754356053908, 5480.780768769284, 5540.889182216414, 5560.082586373203, 5584.748686309126, 1078.4068762180768, 1108.1854836695022, 1113.7215696075982, 1122.2985633978763, 1125.0212315285753, 665.1751827733575, 679.9014142949245, 680.119245576979, 692.394155114275, 692.4979389942251, 11146.108311335074, 11363.715907102223, 11444.94277528607, 11799.705007374712, 12363.814542781865, 703.4372501998381, 709.606168576445, 711.992880071203, 719.5179229915968, 721.5814827400095, 5497.505224529811, 6056.909146362808, 6094.725737341831, 6113.41922517369, 6197.96901015492, 108100.459497702, 110070.44964775284, 111280.0, 112505.43747281375, 115780.42109789397, 87033.56483217671, 93350.53324733333, 94011.52994234985, 97108.51445742726, 99289.0, 81403.18596814075, 83107.5844620773, 84533.0, 85615.57192213999, 86458.13541864627, 10800.783984320113, 10875.564977400816, 10925.0, 10960.12319014461, 11309.660710178709, 39911.0, 40728.389074163315, 41271.58728412678, 41683.37474937818, 43354.34968475538, 51756.741216293674, 52376.47623523716, 52749.0, 53301.4669853312, 54225.0, 15742.9212853935, 16130.193490325672, 18200.635987280446, 18797.34209302668, 18985.240590376783, 21356.7864321361, 21712.782872171076, 21732.891335543853, 21737.891310543346, 22033.118675253452, 18081.27674893042, 19040.523986899883, 19515.219391224193, 19723.704144437557, 19808.306709265682, 18696.6260674783, 18881.150348234638, 19444.611107778048, 19682.50793730168, 19884.60230795405, 19212.0, 19394.127264273422, 19503.317383891495, 19523.51191220231, 19650.410487685393, 13101.148425352703, 13996.16023038621, 14280.42878284857, 14737.705245895237, 15128.0, 12936.935315323362, 14932.0, 15450.22748862575, 15682.451114210948, 15738.606534836721], "value": 9192.241139582986, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1488.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 111.0, 103.0, 107.0, 107.0, 107.0, 110.0, 99.0, 109.0, 108.0], "value": 105.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 118.0, 140.0, 169.0, 117.0, 147.0, 145.0, 121.0, 172.0, 117.0], "value": 138.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 162.0, 160.0, 162.0, 161.0, 167.0, 169.0, 163.0], "value": 162.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 73.0, 179.0, 75.0, 74.0, 75.0, 73.0, 175.0, 75.0], "value": 94.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 83.0, 134.0, 80.0, 81.0, 81.0, 130.0, 79.0, 80.0, 123.0], "value": 95.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 114.0, 113.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 148.0, 148.0, 148.0, 149.0, 148.0, 148.0, 148.0, 149.0, 148.0], "value": 149.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 94.0, 94.0, 94.0, 94.0, 93.0, 95.0, 95.0, 94.0, 96.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 54.0, 53.0, 52.0, 52.0, 52.0, 52.0, 52.0, 53.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 38.0, 37.0, 39.0, 38.0, 43.0, 38.0, 38.0], "value": 38.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 75.0, 74.0, 74.0, 74.0, 75.0, 74.0, 78.0, 76.0], "value": 74.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 119.0, 121.0, 120.0, 123.0, 119.0, 115.0, 119.0, 142.0, 170.0], "value": 126.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 173.0, 183.0, 173.0, 167.0, 177.0, 183.0, 171.0, 175.0, 168.0], "value": 174.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 64.0, 63.0, 70.0, 72.0, 71.0, 65.0, 65.0], "value": 66.8, "unit": "score"}]}]} 11:50:41 INFO - Return code: 0 11:50:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:50:41 INFO - # TBPL SUCCESS # 11:50:41 INFO - Running post-action listener: _package_coverage_data 11:50:41 INFO - Running post-action listener: _resource_record_post_action 11:50:41 INFO - [mozharness: 2017-09-06 18:50:41.771542Z] Finished run-tests step (success) 11:50:41 INFO - Running post-run listener: _resource_record_post_run 11:50:41 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 8192; Write bytes: 391487488; Read time: 168; Write time: 217116 11:50:41 INFO - TinderboxPrint: CPU usage
13.1% 11:50:41 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 168 11:50:41 INFO - TinderboxPrint: I/O write bytes / time
391,487,488 / 217,116 11:50:41 INFO - TinderboxPrint: CPU idle
8,235.5 (86.9%) 11:50:41 INFO - TinderboxPrint: CPU user
1,192.8 (12.6%) 11:50:41 INFO - TinderboxPrint: Swap in / out
0 / 0 11:50:41 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 104357888; Read time: 0; Write time: 83744 11:50:41 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:50:41 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 8192; Write bytes: 283959296; Read time: 168; Write time: 133192 11:50:42 INFO - Running post-run listener: _upload_blobber_files 11:50:42 INFO - Blob upload gear active. 11:50:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:50:42 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:50:42 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:50:42 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:50:42 INFO - (blobuploader) - INFO - Open directory for files ... 11:50:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:50:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:50:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:50:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:50:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:50:43 INFO - (blobuploader) - INFO - Done attempting. 11:50:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:50:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:50:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:50:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:50:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:50:47 INFO - (blobuploader) - INFO - Done attempting. 11:50:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:50:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:50:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:50:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:50:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:50:48 INFO - (blobuploader) - INFO - Done attempting. 11:50:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:50:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:50:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:50:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:50:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:50:49 INFO - (blobuploader) - INFO - Done attempting. 11:50:49 INFO - (blobuploader) - INFO - Iteration through files over. 11:50:49 INFO - Return code: 0 11:50:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:50:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:50:49 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b8b95a9dd57c09feb67ce4b717489a15c8c13681d1999354d8241d499360ce5dc383379ef7a1902c9c6e81431d0f2f4b7c647923aa7c4d807db66ec0f5b0839e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a76caade0968c08ed2c2c7937a1fcaff4fb204f48835785b0575e09134bc2c21f711d6efcdf2116e1e826a218a759627d3c458812800ddc711533ce8d944845f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/909b0ae8c9dd0319be5509d258bd8972122d7676b6e50bbf06a9c1ca6376a2218678b26c2c862945982d176d85e75f9881bdb70c55a690e7d6b2b305568fd4ae", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/32510889638a263aa567575a3741027961ab85b7c98ddfea9d01a423b36abce0a10f87419ef509d730c982f0d76a704b241b2a04c8c920fe3f21e5cb38bec27a"} 11:50:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:50:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:50:49 INFO - Contents: 11:50:49 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b8b95a9dd57c09feb67ce4b717489a15c8c13681d1999354d8241d499360ce5dc383379ef7a1902c9c6e81431d0f2f4b7c647923aa7c4d807db66ec0f5b0839e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a76caade0968c08ed2c2c7937a1fcaff4fb204f48835785b0575e09134bc2c21f711d6efcdf2116e1e826a218a759627d3c458812800ddc711533ce8d944845f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/909b0ae8c9dd0319be5509d258bd8972122d7676b6e50bbf06a9c1ca6376a2218678b26c2c862945982d176d85e75f9881bdb70c55a690e7d6b2b305568fd4ae", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/32510889638a263aa567575a3741027961ab85b7c98ddfea9d01a423b36abce0a10f87419ef509d730c982f0d76a704b241b2a04c8c920fe3f21e5cb38bec27a"} 11:50:49 INFO - Running post-run listener: copy_logs_to_upload_dir 11:50:49 INFO - Copying logs to upload dir... 11:50:49 INFO - mkdir: /builds/slave/test/build/upload/logs 11:50:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.938891 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-09-06 11:50:49.181530) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-06 11:50:49.184788) ========= 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=4300f881736b422476174874000001bc-1504722597.63712-1800445697 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/b8b95a9dd57c09feb67ce4b717489a15c8c13681d1999354d8241d499360ce5dc383379ef7a1902c9c6e81431d0f2f4b7c647923aa7c4d807db66ec0f5b0839e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a76caade0968c08ed2c2c7937a1fcaff4fb204f48835785b0575e09134bc2c21f711d6efcdf2116e1e826a218a759627d3c458812800ddc711533ce8d944845f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/909b0ae8c9dd0319be5509d258bd8972122d7676b6e50bbf06a9c1ca6376a2218678b26c2c862945982d176d85e75f9881bdb70c55a690e7d6b2b305568fd4ae", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/32510889638a263aa567575a3741027961ab85b7c98ddfea9d01a423b36abce0a10f87419ef509d730c982f0d76a704b241b2a04c8c920fe3f21e5cb38bec27a"} build_url:https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011456 build_url: 'https://queue.taskcluster.net/v1/task/CNiQiOuNT32DU3L464s6DA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b8b95a9dd57c09feb67ce4b717489a15c8c13681d1999354d8241d499360ce5dc383379ef7a1902c9c6e81431d0f2f4b7c647923aa7c4d807db66ec0f5b0839e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a76caade0968c08ed2c2c7937a1fcaff4fb204f48835785b0575e09134bc2c21f711d6efcdf2116e1e826a218a759627d3c458812800ddc711533ce8d944845f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/909b0ae8c9dd0319be5509d258bd8972122d7676b6e50bbf06a9c1ca6376a2218678b26c2c862945982d176d85e75f9881bdb70c55a690e7d6b2b305568fd4ae", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/32510889638a263aa567575a3741027961ab85b7c98ddfea9d01a423b36abce0a10f87419ef509d730c982f0d76a704b241b2a04c8c920fe3f21e5cb38bec27a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-06 11:50:49.212388) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 11:50:49.212702) ========= 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=4300f881736b422476174874000001bc-1504722597.63712-1800445697 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004629 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 11:50:49.298856) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-06 11:50:49.299169) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-06 11:50:49.299464) ========= ========= Total master_lag: 0.21 =========