builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-039 starttime: 1504311341.3 results: success (0) revision: 5278dfcf5eb9f58eaf06ad1ce67e7fd4aba34772 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 17:15:41.304226) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 17:15:41.304693) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 17:15:41.304963) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 17:15:41.322618) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 17:15:41.322992) ========= 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=5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-01 17:15:41-- 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% 87.2M=0s 2017-09-01 17:15:41 (87.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.096453 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 17:15:41.439280) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 17:15:41.439635) ========= 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=5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015940 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 17:15:41.493536) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-09-01 17:15:41.494164) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5278dfcf5eb9f58eaf06ad1ce67e7fd4aba34772 --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 5278dfcf5eb9f58eaf06ad1ce67e7fd4aba34772 --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=5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-01 17:15:42,080 truncating revision to first 12 chars 2017-09-01 17:15:42,080 Setting DEBUG logging. 2017-09-01 17:15:42,080 attempt 1/10 2017-09-01 17:15:42,080 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5278dfcf5eb9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-01 17:15:42,553 unpacking tar archive at: mozilla-central-5278dfcf5eb9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.988095 ========= master_lag: 1.48 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-09-01 17:15:43.964758) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 17:15:43.969155) ========= script_repo_revision: 5278dfcf5eb9f58eaf06ad1ce67e7fd4aba34772 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 17:15:43.969640) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 17:15:43.969904) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 17:15:43.987978) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-09-01 17:15:43.988359) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:15:44 INFO - MultiFileLogger online at 20170901 17:15:44 in /builds/slave/test 17:15:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:15:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:15:44 INFO - {'append_to_log': False, 17:15:44 INFO - 'base_work_dir': '/builds/slave/test', 17:15:44 INFO - 'blob_upload_branch': 'Firefox', 17:15:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:15:44 INFO - 'branch': 'Firefox', 17:15:44 INFO - 'buildbot_json_path': 'buildprops.json', 17:15:44 INFO - 'code_coverage': False, 17:15:44 INFO - 'config_files': ('talos/linux_config.py',), 17:15:44 INFO - 'default_actions': ('clobber', 17:15:44 INFO - 'read-buildbot-config', 17:15:44 INFO - 'download-and-extract', 17:15:44 INFO - 'populate-webroot', 17:15:44 INFO - 'create-virtualenv', 17:15:44 INFO - 'install', 17:15:44 INFO - 'setup-mitmproxy', 17:15:44 INFO - 'run-tests'), 17:15:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:15:44 INFO - 'disable_ccov_upload': False, 17:15:44 INFO - 'disable_stylo': False, 17:15:44 INFO - 'download_minidump_stackwalk': True, 17:15:44 INFO - 'download_symbols': 'ondemand', 17:15:44 INFO - 'enable_stylo': False, 17:15:44 INFO - 'enable_webrender': False, 17:15:44 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 17:15:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:15:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:15:44 INFO - 'gecko_profile': False, 17:15:44 INFO - 'gecko_profile_interval': 0, 17:15:44 INFO - 'installer_path': 'installer.exe', 17:15:44 INFO - 'log_level': 'info', 17:15:44 INFO - 'log_name': 'talos', 17:15:44 INFO - 'log_to_console': True, 17:15:44 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:15:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:15:44 INFO - 'opt_config_files': (), 17:15:44 INFO - 'pip_index': False, 17:15:44 INFO - 'suite': 'dromaeojs-e10s', 17:15:44 INFO - 'system_bits': '32', 17:15:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:15:44 INFO - 'title': 'talos-linux64-ix-039', 17:15:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:15:44 INFO - 'use_talos_json': True, 17:15:44 INFO - 'verify': 'False', 17:15:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:15:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:15:44 INFO - 'work_dir': 'build'} 17:15:44 INFO - [mozharness: 2017-09-02 00:15:44.707719Z] Running clobber step. 17:15:44 INFO - Running pre-action listener: _resource_record_pre_action 17:15:44 INFO - Running main action method: clobber 17:15:44 INFO - rmtree: /builds/slave/test/build 17:15:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:15:46 INFO - Running post-action listener: _resource_record_post_action 17:15:46 INFO - [mozharness: 2017-09-02 00:15:46.226898Z] Finished clobber step (success) 17:15:46 INFO - [mozharness: 2017-09-02 00:15:46.226994Z] Running read-buildbot-config step. 17:15:46 INFO - Running pre-action listener: _resource_record_pre_action 17:15:46 INFO - Running main action method: read_buildbot_config 17:15:46 INFO - Using buildbot properties: 17:15:46 INFO - { 17:15:46 INFO - "project": "", 17:15:46 INFO - "product": "firefox", 17:15:46 INFO - "who": "kwierso@gmail.com", 17:15:46 INFO - "installer_path": "public/build/target.tar.bz2", 17:15:46 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 17:15:46 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 17:15:46 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 17:15:46 INFO - "stage_platform": "linux64-qr", 17:15:46 INFO - "basedir": "/builds/slave/test", 17:15:46 INFO - "buildnumber": 28, 17:15:46 INFO - "platform": "ubuntu64_hw_qr", 17:15:46 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:15:46 INFO - "slavebuilddir": "test", 17:15:46 INFO - "taskId": "H0XdQJlWSXaUjs8a3kjgWw", 17:15:46 INFO - "branch": "mozilla-central", 17:15:46 INFO - "script_repo_revision": "production", 17:15:46 INFO - "revision": "5278dfcf5eb9f58eaf06ad1ce67e7fd4aba34772", 17:15:46 INFO - "slavename": "talos-linux64-ix-039", 17:15:46 INFO - "repo_path": "mozilla-central" 17:15:46 INFO - } 17:15:46 INFO - Finding installer, test and symbols from parent task. 17:15:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H0XdQJlWSXaUjs8a3kjgWw'}, attempt #1 17:15:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H0XdQJlWSXaUjs8a3kjgWw'}, attempt #1 17:15:47 INFO - Task dependencies: Dw0q0U3MRZ6Rav7W5HAwLw 17:15:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw'}, attempt #1 17:15:47 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.tar.bz2 17:15:47 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.test_packages.json 17:15:47 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.crashreporter-symbols.zip 17:15:47 INFO - Running post-action listener: _resource_record_post_action 17:15:47 INFO - [mozharness: 2017-09-02 00:15:47.616255Z] Finished read-buildbot-config step (success) 17:15:47 INFO - [mozharness: 2017-09-02 00:15:47.616472Z] Running download-and-extract step. 17:15:47 INFO - Running pre-action listener: _resource_record_pre_action 17:15:47 INFO - Running main action method: download_and_extract 17:15:47 INFO - mkdir: /builds/slave/test/build/tests 17:15:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:15:47 INFO - trying https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.test_packages.json 17:15:47 INFO - Downloading https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:15:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:15:48 INFO - Downloaded 989 bytes. 17:15:48 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:15:48 INFO - Using the following test package requirements: 17:15:48 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:15:48 INFO - u'common': [u'target.common.tests.zip'], 17:15:48 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:15:48 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:15:48 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:15:48 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:15:48 INFO - u'mozbase': [u'target.common.tests.zip'], 17:15:48 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:15:48 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:15:48 INFO - u'web-platform': [u'target.common.tests.zip', 17:15:48 INFO - u'target.web-platform.tests.tar.gz'], 17:15:48 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:15:48 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 17:15:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.common.tests.zip 17:15:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:15:48 INFO - Fetch https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.common.tests.zip into memory 17:15:49 INFO - Content-Length response header: 54958091 17:15:49 INFO - Bytes received: 54958091 17:16:00 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 17:16:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.common.tests.zip 17:16:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:16:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.common.tests.zip into memory 17:16:01 INFO - Content-Length response header: 54958091 17:16:01 INFO - Bytes received: 54958091 17:16:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.talos.tests.zip 17:16:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:16:13 INFO - Fetch https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.talos.tests.zip into memory 17:16:13 INFO - Content-Length response header: 14496650 17:16:13 INFO - Bytes received: 14496650 17:16:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:14 INFO - trying https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.tar.bz2 17:16:14 INFO - Downloading https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:16:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:16:26 INFO - Downloaded 62544014 bytes. 17:16:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.tar.bz2 17:16:26 INFO - mkdir: /builds/slave/test/properties 17:16:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:16:26 INFO - Writing to file /builds/slave/test/properties/build_url 17:16:26 INFO - Contents: 17:16:26 INFO - build_url:https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.tar.bz2 17:16:26 INFO - Running post-action listener: _resource_record_post_action 17:16:26 INFO - Running post-action listener: find_tests_for_verification 17:16:26 INFO - Running post-action listener: set_extra_try_arguments 17:16:26 INFO - [mozharness: 2017-09-02 00:16:26.997489Z] Finished download-and-extract step (success) 17:16:26 INFO - [mozharness: 2017-09-02 00:16:26.997715Z] Running populate-webroot step. 17:16:26 INFO - Running pre-action listener: _resource_record_pre_action 17:16:26 INFO - Running main action method: populate_webroot 17:16:27 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:16:27 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 17:16:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:16:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:16:27 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 17:16:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:16:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:16:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:16:27 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 17:16:27 INFO - 'talos_options': ['--enable-stylo'], 17:16:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:16:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:16:27 INFO - 'tests': ['damp', 'tps']}, 17:16:27 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 17:16:27 INFO - 'talos_options': ['--enable-stylo'], 17:16:27 INFO - 'tests': ['damp', 'tps']}, 17:16:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:16:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:16:27 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 17:16:27 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:16:27 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:16:27 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:16:27 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 17:16:27 INFO - 'talos_options': ['--enable-stylo'], 17:16:27 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:16:27 INFO - 'other-e10s': {'tests': ['a11yr', 17:16:27 INFO - 'ts_paint', 17:16:27 INFO - 'tpaint', 17:16:27 INFO - 'sessionrestore', 17:16:27 INFO - 'sessionrestore_many_windows', 17:16:27 INFO - 'sessionrestore_no_auto_restore', 17:16:27 INFO - 'tabpaint']}, 17:16:27 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 17:16:27 INFO - 'tests': ['a11yr', 17:16:27 INFO - 'ts_paint', 17:16:27 INFO - 'tpaint', 17:16:27 INFO - 'sessionrestore', 17:16:27 INFO - 'sessionrestore_many_windows', 17:16:27 INFO - 'sessionrestore_no_auto_restore', 17:16:27 INFO - 'tabpaint']}, 17:16:27 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 17:16:27 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 17:16:27 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 17:16:27 INFO - 'tests': ['bloom_basic_singleton']}, 17:16:27 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 17:16:27 INFO - 'tests': ['bloom_basic']}, 17:16:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:16:27 INFO - 'tsvgr_opacity', 17:16:27 INFO - 'tart', 17:16:27 INFO - 'tscrollx', 17:16:27 INFO - 'tsvg_static']}, 17:16:27 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 17:16:27 INFO - 'tests': ['tsvgx', 17:16:27 INFO - 'tsvgr_opacity', 17:16:27 INFO - 'tart', 17:16:27 INFO - 'tscrollx', 17:16:27 INFO - 'tsvg_static']}, 17:16:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:16:27 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 17:16:27 INFO - 'talos_options': ['--enable-stylo'], 17:16:27 INFO - 'tests': ['tp5o']}, 17:16:27 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:16:27 INFO - 'talos_options': ['--mitmproxy', 17:16:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:16:27 INFO - '--firstNonBlankPaint'], 17:16:27 INFO - 'tests': ['tp6_google', 17:16:27 INFO - 'tp6_youtube', 17:16:27 INFO - 'tp6_amazon', 17:16:27 INFO - 'tp6_facebook']}, 17:16:27 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:16:27 INFO - 'talos_options': ['--enable-stylo', 17:16:27 INFO - '--mitmproxy', 17:16:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:16:27 INFO - '--firstNonBlankPaint'], 17:16:27 INFO - 'tests': ['tp6_google', 17:16:27 INFO - 'tp6_youtube', 17:16:27 INFO - 'tp6_amazon', 17:16:27 INFO - 'tp6_facebook']}, 17:16:27 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:16:27 INFO - 'talos_options': ['--enable-stylo', 17:16:27 INFO - '--stylo-threads=1', 17:16:27 INFO - '--mitmproxy', 17:16:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:16:27 INFO - '--firstNonBlankPaint'], 17:16:27 INFO - 'tests': ['tp6_google', 17:16:27 INFO - 'tp6_youtube', 17:16:27 INFO - 'tp6_amazon', 17:16:27 INFO - 'tp6_facebook']}, 17:16:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:16:27 INFO - 'talos_options': ['--xperf_path', 17:16:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:16:27 INFO - 'tests': ['tp5n']}, 17:16:27 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 17:16:27 INFO - 'talos_options': ['--enable-stylo', 17:16:27 INFO - '--xperf_path', 17:16:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:16:27 INFO - 'tests': ['tp5n']}}} 17:16:27 INFO - Running post-action listener: _resource_record_post_action 17:16:27 INFO - [mozharness: 2017-09-02 00:16:27.022401Z] Finished populate-webroot step (success) 17:16:27 INFO - [mozharness: 2017-09-02 00:16:27.022499Z] Running create-virtualenv step. 17:16:27 INFO - Running pre-action listener: _resource_record_pre_action 17:16:27 INFO - Running main action method: create_virtualenv 17:16:27 INFO - Creating virtualenv /builds/slave/test/build/venv 17:16:27 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:16:27 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 17:16:27 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:16:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:16:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:16:27 INFO - Installing distribute.............................................................................................................................................................................................done. 17:16:27 INFO - Installing pip...............done. 17:16:27 INFO - Return code: 0 17:16:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:16:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:16:28 INFO - Reading from file tmpfile_stdout 17:16:28 INFO - Output received: 17:16:28 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 17:16:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:16:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf86a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a090>, '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 0xf8ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf84f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', '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 17:16:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:16:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:16:28 INFO - Using env: {'DISPLAY': ':0', 17:16:28 INFO - 'HOME': '/home/cltbld', 17:16:28 INFO - 'LANG': 'en_US.UTF-8', 17:16:28 INFO - 'LANGUAGE': 'en_US:en', 17:16:28 INFO - 'LOGNAME': 'cltbld', 17:16:28 INFO - 'MAIL': '/var/mail/cltbld', 17:16:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:28 INFO - 'MOZ_NO_REMOTE': '1', 17:16:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:28 INFO - 'NO_EM_RESTART': '1', 17:16:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:28 INFO - 'PWD': '/builds/slave/test', 17:16:28 INFO - 'SHELL': '/bin/bash', 17:16:28 INFO - 'SHLVL': '1', 17:16:28 INFO - 'TERM': 'linux', 17:16:28 INFO - 'TMOUT': '86400', 17:16:28 INFO - 'USER': 'cltbld', 17:16:28 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', 17:16:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:28 INFO - '_': '/tools/buildbot/bin/python'} 17:16:30 INFO - Ignoring indexes: http://pypi.python.org/simple/ 17:16:30 INFO - Downloading/unpacking pip>=1.5 17:16:30 INFO - Running setup.py egg_info for package pip 17:16:30 INFO - warning: no files found matching 'pip/cacert.pem' 17:16:30 INFO - warning: no files found matching '*.html' under directory 'docs' 17:16:30 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:16:30 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:16:30 INFO - Installing collected packages: pip 17:16:30 INFO - Found existing installation: pip 1.1 17:16:30 INFO - Uninstalling pip: 17:16:30 INFO - Successfully uninstalled pip 17:16:30 INFO - Running setup.py install for pip 17:16:30 INFO - warning: no files found matching 'pip/cacert.pem' 17:16:30 INFO - warning: no files found matching '*.html' under directory 'docs' 17:16:30 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:16:30 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:16:30 INFO - Installing pip script to /builds/slave/test/build/venv/bin 17:16:30 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 17:16:30 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 17:16:30 INFO - Successfully installed pip 17:16:30 INFO - Cleaning up... 17:16:30 INFO - Return code: 0 17:16:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xc1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf86a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a090>, '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 0xf8ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf84f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', '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 17:16:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:16:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:16:30 INFO - Using env: {'DISPLAY': ':0', 17:16:30 INFO - 'HOME': '/home/cltbld', 17:16:30 INFO - 'LANG': 'en_US.UTF-8', 17:16:30 INFO - 'LANGUAGE': 'en_US:en', 17:16:30 INFO - 'LOGNAME': 'cltbld', 17:16:30 INFO - 'MAIL': '/var/mail/cltbld', 17:16:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:30 INFO - 'MOZ_NO_REMOTE': '1', 17:16:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:30 INFO - 'NO_EM_RESTART': '1', 17:16:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:30 INFO - 'PWD': '/builds/slave/test', 17:16:30 INFO - 'SHELL': '/bin/bash', 17:16:30 INFO - 'SHLVL': '1', 17:16:30 INFO - 'TERM': 'linux', 17:16:30 INFO - 'TMOUT': '86400', 17:16:30 INFO - 'USER': 'cltbld', 17:16:30 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', 17:16:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:30 INFO - '_': '/tools/buildbot/bin/python'} 17:16:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:30 INFO - Downloading/unpacking psutil>=3.1.1 17:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:16:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:16:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:16:31 INFO - Installing collected packages: psutil 17:16:31 INFO - Running setup.py install for psutil 17:16:31 INFO - building 'psutil._psutil_linux' extension 17:16:31 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 17:16:31 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 17:16:31 INFO - building 'psutil._psutil_posix' extension 17:16:31 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 17:16:32 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 17:16:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:16:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:16:32 INFO - Successfully installed psutil 17:16:32 INFO - Cleaning up... 17:16:32 INFO - Return code: 0 17:16:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:16:32 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')]} 17:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:32 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')]} 17:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:32 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 0xc1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf86a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a090>, '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 0xf8ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf84f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', '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 17:16:32 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 17:16:32 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 17:16:32 INFO - Using env: {'DISPLAY': ':0', 17:16:32 INFO - 'HOME': '/home/cltbld', 17:16:32 INFO - 'LANG': 'en_US.UTF-8', 17:16:32 INFO - 'LANGUAGE': 'en_US:en', 17:16:32 INFO - 'LOGNAME': 'cltbld', 17:16:32 INFO - 'MAIL': '/var/mail/cltbld', 17:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:32 INFO - 'MOZ_NO_REMOTE': '1', 17:16:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:32 INFO - 'NO_EM_RESTART': '1', 17:16:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:32 INFO - 'PWD': '/builds/slave/test', 17:16:32 INFO - 'SHELL': '/bin/bash', 17:16:32 INFO - 'SHLVL': '1', 17:16:32 INFO - 'TERM': 'linux', 17:16:32 INFO - 'TMOUT': '86400', 17:16:32 INFO - 'USER': 'cltbld', 17:16:32 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', 17:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:32 INFO - '_': '/tools/buildbot/bin/python'} 17:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:16:32 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 17:16:32 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 17:16:32 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 17:16:32 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 17:16:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:16:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:16:33 INFO - Installing collected packages: mozsystemmonitor 17:16:33 INFO - Running setup.py install for mozsystemmonitor 17:16:33 INFO - Successfully installed mozsystemmonitor 17:16:33 INFO - Cleaning up... 17:16:33 INFO - Return code: 0 17:16:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:16: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')]} 17:16:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16: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')]} 17:16:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf86a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a090>, '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 0xf8ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf84f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', '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 17:16:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:16:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:16:33 INFO - Using env: {'DISPLAY': ':0', 17:16:33 INFO - 'HOME': '/home/cltbld', 17:16:33 INFO - 'LANG': 'en_US.UTF-8', 17:16:33 INFO - 'LANGUAGE': 'en_US:en', 17:16:33 INFO - 'LOGNAME': 'cltbld', 17:16:33 INFO - 'MAIL': '/var/mail/cltbld', 17:16:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:33 INFO - 'MOZ_NO_REMOTE': '1', 17:16:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:33 INFO - 'NO_EM_RESTART': '1', 17:16:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:33 INFO - 'PWD': '/builds/slave/test', 17:16:33 INFO - 'SHELL': '/bin/bash', 17:16:33 INFO - 'SHLVL': '1', 17:16:33 INFO - 'TERM': 'linux', 17:16:33 INFO - 'TMOUT': '86400', 17:16:33 INFO - 'USER': 'cltbld', 17:16:33 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', 17:16:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:33 INFO - '_': '/tools/buildbot/bin/python'} 17:16:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:33 INFO - Downloading/unpacking jsonschema==2.5.1 17:16:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:16:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:35 INFO - Downloading functools32-3.2.3-2.tar.gz 17:16:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:16:35 INFO - warning: no files found matching '*.txt' 17:16:35 INFO - no previously-included directories found matching 'build' 17:16:35 INFO - no previously-included directories found matching 'dist' 17:16:35 INFO - no previously-included directories found matching '.git*' 17:16:35 INFO - Installing collected packages: jsonschema, functools32 17:16:35 INFO - Running setup.py install for functools32 17:16:35 INFO - warning: no files found matching '*.txt' 17:16:35 INFO - no previously-included directories found matching 'build' 17:16:35 INFO - no previously-included directories found matching 'dist' 17:16:35 INFO - no previously-included directories found matching '.git*' 17:16:35 INFO - Successfully installed jsonschema functools32 17:16:35 INFO - Cleaning up... 17:16:35 INFO - Return code: 0 17:16:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xc1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf86a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a090>, '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 0xf8ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf84f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', '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 17:16:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:16:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:16:35 INFO - Using env: {'DISPLAY': ':0', 17:16:35 INFO - 'HOME': '/home/cltbld', 17:16:35 INFO - 'LANG': 'en_US.UTF-8', 17:16:35 INFO - 'LANGUAGE': 'en_US:en', 17:16:35 INFO - 'LOGNAME': 'cltbld', 17:16:35 INFO - 'MAIL': '/var/mail/cltbld', 17:16:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:35 INFO - 'MOZ_NO_REMOTE': '1', 17:16:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:35 INFO - 'NO_EM_RESTART': '1', 17:16:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:35 INFO - 'PWD': '/builds/slave/test', 17:16:35 INFO - 'SHELL': '/bin/bash', 17:16:35 INFO - 'SHLVL': '1', 17:16:35 INFO - 'TERM': 'linux', 17:16:35 INFO - 'TMOUT': '86400', 17:16:35 INFO - 'USER': 'cltbld', 17:16:35 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', 17:16:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:35 INFO - '_': '/tools/buildbot/bin/python'} 17:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:16:35 INFO - Cleaning up... 17:16:35 INFO - Return code: 0 17:16:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf86a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a090>, '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 0xf8ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf84f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', '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 17:16:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:16:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:16:35 INFO - Using env: {'DISPLAY': ':0', 17:16:35 INFO - 'HOME': '/home/cltbld', 17:16:35 INFO - 'LANG': 'en_US.UTF-8', 17:16:35 INFO - 'LANGUAGE': 'en_US:en', 17:16:35 INFO - 'LOGNAME': 'cltbld', 17:16:35 INFO - 'MAIL': '/var/mail/cltbld', 17:16:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:35 INFO - 'MOZ_NO_REMOTE': '1', 17:16:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:35 INFO - 'NO_EM_RESTART': '1', 17:16:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:35 INFO - 'PWD': '/builds/slave/test', 17:16:35 INFO - 'SHELL': '/bin/bash', 17:16:35 INFO - 'SHLVL': '1', 17:16:35 INFO - 'TERM': 'linux', 17:16:35 INFO - 'TMOUT': '86400', 17:16:35 INFO - 'USER': 'cltbld', 17:16:35 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', 17:16:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:35 INFO - '_': '/tools/buildbot/bin/python'} 17:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:35 INFO - Downloading/unpacking blobuploader==1.2.4 17:16: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 17:16: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 17:16: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 17:16: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 17:16:36 INFO - Downloading blobuploader-1.2.4.tar.gz 17:16:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:16:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:16:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:16: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 17:16: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 17:16: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 17:16: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 17:16:37 INFO - Downloading docopt-0.6.1.tar.gz 17:16:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:16:37 INFO - Installing collected packages: blobuploader, requests, docopt 17:16:37 INFO - Running setup.py install for blobuploader 17:16:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:16:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:16:37 INFO - Running setup.py install for requests 17:16:38 INFO - Running setup.py install for docopt 17:16:38 INFO - Successfully installed blobuploader requests docopt 17:16:38 INFO - Cleaning up... 17:16:38 INFO - Return code: 0 17:16:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:16: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')]} 17:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16: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')]} 17:16:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf86a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a090>, '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 0xf8ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf84f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', '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 17:16:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:16:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:16:38 INFO - Using env: {'DISPLAY': ':0', 17:16:38 INFO - 'HOME': '/home/cltbld', 17:16:38 INFO - 'LANG': 'en_US.UTF-8', 17:16:38 INFO - 'LANGUAGE': 'en_US:en', 17:16:38 INFO - 'LOGNAME': 'cltbld', 17:16:38 INFO - 'MAIL': '/var/mail/cltbld', 17:16:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:38 INFO - 'MOZ_NO_REMOTE': '1', 17:16:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:38 INFO - 'NO_EM_RESTART': '1', 17:16:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:38 INFO - 'PWD': '/builds/slave/test', 17:16:38 INFO - 'SHELL': '/bin/bash', 17:16:38 INFO - 'SHLVL': '1', 17:16:38 INFO - 'TERM': 'linux', 17:16:38 INFO - 'TMOUT': '86400', 17:16:38 INFO - 'USER': 'cltbld', 17:16:38 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', 17:16:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:38 INFO - '_': '/tools/buildbot/bin/python'} 17:16:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:16:38 INFO - Running setup.py (path:/tmp/pip-2Myeka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:16:38 INFO - Running setup.py (path:/tmp/pip-7XoHoh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:16:38 INFO - Running setup.py (path:/tmp/pip-i_ghkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:16:38 INFO - Running setup.py (path:/tmp/pip-cZJJb0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:16:38 INFO - Running setup.py (path:/tmp/pip-UDXTVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:16:38 INFO - Running setup.py (path:/tmp/pip-mBUSx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:16:38 INFO - Running setup.py (path:/tmp/pip-uk9cmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:16:38 INFO - Running setup.py (path:/tmp/pip-Vvtvv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:16:38 INFO - Running setup.py (path:/tmp/pip-Luf4iQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:16:39 INFO - Running setup.py (path:/tmp/pip-IOx77E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:16:39 INFO - Running setup.py (path:/tmp/pip-ojrrA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:16:39 INFO - Running setup.py (path:/tmp/pip-wkdPtO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:16:39 INFO - Running setup.py (path:/tmp/pip-whTNaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:16:39 INFO - Running setup.py (path:/tmp/pip-7ICVhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:39 INFO - Running setup.py (path:/tmp/pip-YKLEK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:16:39 INFO - Running setup.py (path:/tmp/pip-g6TcUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:16:39 INFO - Running setup.py (path:/tmp/pip-nBkz2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:16:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:16:39 INFO - Running setup.py install for manifestparser 17:16:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:16:39 INFO - Running setup.py install for mozcrash 17:16:39 INFO - Running setup.py install for mozdebug 17:16:39 INFO - Running setup.py install for mozdevice 17:16:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:16:39 INFO - Running setup.py install for mozfile 17:16:40 INFO - Running setup.py install for mozhttpd 17:16:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:16:40 INFO - Running setup.py install for mozinfo 17:16:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:16:40 INFO - Running setup.py install for mozInstall 17:16:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:16:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:16:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:16:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:16:40 INFO - Running setup.py install for mozleak 17:16:40 INFO - Running setup.py install for mozlog 17:16:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:16:40 INFO - Running setup.py install for moznetwork 17:16:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:16:40 INFO - Running setup.py install for mozprocess 17:16:40 INFO - Running setup.py install for mozprofile 17:16:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:16:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:16:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:16:41 INFO - Running setup.py install for mozrunner 17:16:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:16:41 INFO - Running setup.py install for mozscreenshot 17:16:41 INFO - Running setup.py install for moztest 17:16:41 INFO - Running setup.py install for mozversion 17:16:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:16:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:16:41 INFO - Cleaning up... 17:16:41 INFO - Return code: 0 17:16:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:41 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 0xc1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf86a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a090>, '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 0xf8ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf84f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', '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 17:16:41 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 17:16:41 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 17:16:41 INFO - Using env: {'DISPLAY': ':0', 17:16:41 INFO - 'HOME': '/home/cltbld', 17:16:41 INFO - 'LANG': 'en_US.UTF-8', 17:16:41 INFO - 'LANGUAGE': 'en_US:en', 17:16:41 INFO - 'LOGNAME': 'cltbld', 17:16:41 INFO - 'MAIL': '/var/mail/cltbld', 17:16:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:41 INFO - 'MOZ_NO_REMOTE': '1', 17:16:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:41 INFO - 'NO_EM_RESTART': '1', 17:16:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:41 INFO - 'PWD': '/builds/slave/test', 17:16:41 INFO - 'SHELL': '/bin/bash', 17:16:41 INFO - 'SHLVL': '1', 17:16:41 INFO - 'TERM': 'linux', 17:16:41 INFO - 'TMOUT': '86400', 17:16:41 INFO - 'USER': 'cltbld', 17:16:41 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', 17:16:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:41 INFO - '_': '/tools/buildbot/bin/python'} 17:16:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:16:41 INFO - Running setup.py (path:/tmp/pip-DvHxKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:16:41 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:16:41 INFO - Running setup.py (path:/tmp/pip-9twvp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:16:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:16:41 INFO - Running setup.py (path:/tmp/pip-kev4lw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:16:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:16:41 INFO - Running setup.py (path:/tmp/pip-vC0uQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:16:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:16:41 INFO - Running setup.py (path:/tmp/pip-kEwUG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:16:42 INFO - Running setup.py (path:/tmp/pip-tFrFp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:16:42 INFO - Running setup.py (path:/tmp/pip-0DbbHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:16:42 INFO - Running setup.py (path:/tmp/pip-nFOK2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:16:42 INFO - Running setup.py (path:/tmp/pip-IaC4Pv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:16:42 INFO - Running setup.py (path:/tmp/pip-vXbtjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:16:42 INFO - Running setup.py (path:/tmp/pip-a8_jYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:16:42 INFO - Running setup.py (path:/tmp/pip-2xcVZ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:16:42 INFO - Running setup.py (path:/tmp/pip-uMDZue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:16:42 INFO - Running setup.py (path:/tmp/pip-aKUXzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:42 INFO - Running setup.py (path:/tmp/pip-QjZXLp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:16:42 INFO - Running setup.py (path:/tmp/pip-Cm5uvP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:16:42 INFO - Running setup.py (path:/tmp/pip-UGz89r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:16:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:44 INFO - Downloading blessings-1.6.tar.gz 17:16:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:16:44 INFO - Installing collected packages: blessings 17:16:44 INFO - Running setup.py install for blessings 17:16:44 INFO - Successfully installed blessings 17:16:44 INFO - Cleaning up... 17:16:44 INFO - Return code: 0 17:16:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:16:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:16:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:16:44 INFO - Reading from file tmpfile_stdout 17:16:44 INFO - Current package versions: 17:16:44 INFO - argparse == 1.2.1 17:16:44 INFO - blessings == 1.6 17:16:44 INFO - blobuploader == 1.2.4 17:16:44 INFO - docopt == 0.6.1 17:16:44 INFO - functools32 == 3.2.3-2 17:16:44 INFO - jsonschema == 2.5.1 17:16:44 INFO - manifestparser == 1.1 17:16:44 INFO - mozInstall == 1.13 17:16:44 INFO - mozcrash == 1.0 17:16:44 INFO - mozdebug == 0.1 17:16:44 INFO - mozdevice == 0.51 17:16:44 INFO - mozfile == 1.2 17:16:44 INFO - mozhttpd == 0.7 17:16:44 INFO - mozinfo == 0.10 17:16:44 INFO - mozleak == 0.1 17:16:44 INFO - mozlog == 3.5 17:16:44 INFO - moznetwork == 0.27 17:16:44 INFO - mozprocess == 0.25 17:16:44 INFO - mozprofile == 0.28 17:16:44 INFO - mozrunner == 6.13 17:16:44 INFO - mozscreenshot == 0.1 17:16:44 INFO - mozsystemmonitor == 0.3 17:16:44 INFO - moztest == 0.8 17:16:44 INFO - mozversion == 1.4 17:16:44 INFO - psutil == 3.1.1 17:16:44 INFO - requests == 1.2.3 17:16:44 INFO - wsgiref == 0.1.2 17:16:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:16:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf86a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a090>, '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 0xf8ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf84f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', '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 17:16:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:16:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:16:44 INFO - Using env: {'DISPLAY': ':0', 17:16:44 INFO - 'HOME': '/home/cltbld', 17:16:44 INFO - 'LANG': 'en_US.UTF-8', 17:16:44 INFO - 'LANGUAGE': 'en_US:en', 17:16:44 INFO - 'LOGNAME': 'cltbld', 17:16:44 INFO - 'MAIL': '/var/mail/cltbld', 17:16:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:44 INFO - 'MOZ_NO_REMOTE': '1', 17:16:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:44 INFO - 'NO_EM_RESTART': '1', 17:16:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:44 INFO - 'PWD': '/builds/slave/test', 17:16:44 INFO - 'SHELL': '/bin/bash', 17:16:44 INFO - 'SHLVL': '1', 17:16:44 INFO - 'TERM': 'linux', 17:16:44 INFO - 'TMOUT': '86400', 17:16:44 INFO - 'USER': 'cltbld', 17:16:44 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', 17:16:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:44 INFO - '_': '/tools/buildbot/bin/python'} 17:16:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:44 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)) 17:16:44 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)) 17:16:44 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)) 17:16:44 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)) 17:16:44 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)) 17:16:44 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)) 17:16:44 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)) 17:16:44 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)) 17:16:44 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)) 17:16:44 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:16:44 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 17:16:44 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 17:16:44 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 17:16:44 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 17:16:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 17:16:45 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)) 17:16:45 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)) 17:16:45 INFO - Installing collected packages: simplejson 17:16:45 INFO - Running setup.py install for simplejson 17:16:45 INFO - building 'simplejson._speedups' extension 17:16:45 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 17:16:46 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 17:16:46 INFO - Successfully installed simplejson 17:16:46 INFO - Cleaning up... 17:16:46 INFO - Return code: 0 17:16:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:46 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 0xc1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf86a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a090>, '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 0xf8ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf84f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', '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 17:16:46 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 17:16:46 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 17:16:46 INFO - Using env: {'DISPLAY': ':0', 17:16:46 INFO - 'HOME': '/home/cltbld', 17:16:46 INFO - 'LANG': 'en_US.UTF-8', 17:16:46 INFO - 'LANGUAGE': 'en_US:en', 17:16:46 INFO - 'LOGNAME': 'cltbld', 17:16:46 INFO - 'MAIL': '/var/mail/cltbld', 17:16:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:46 INFO - 'MOZ_NO_REMOTE': '1', 17:16:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:46 INFO - 'NO_EM_RESTART': '1', 17:16:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:46 INFO - 'PWD': '/builds/slave/test', 17:16:46 INFO - 'SHELL': '/bin/bash', 17:16:46 INFO - 'SHLVL': '1', 17:16:46 INFO - 'TERM': 'linux', 17:16:46 INFO - 'TMOUT': '86400', 17:16:46 INFO - 'USER': 'cltbld', 17:16:46 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', 17:16:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:46 INFO - '_': '/tools/buildbot/bin/python'} 17:16:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:16:47 INFO - Cleaning up... 17:16:47 INFO - Return code: 0 17:16:47 INFO - Running post-action listener: _resource_record_post_action 17:16:47 INFO - Running post-action listener: _start_resource_monitoring 17:16:47 INFO - Starting resource monitoring. 17:16:47 INFO - [mozharness: 2017-09-02 00:16:47.197474Z] Finished create-virtualenv step (success) 17:16:47 INFO - [mozharness: 2017-09-02 00:16:47.197945Z] Running install step. 17:16:47 INFO - Running pre-action listener: _resource_record_pre_action 17:16:47 INFO - Running main action method: install 17:16:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:16:47 INFO - Reading from file tmpfile_stdout 17:16:47 INFO - Detecting whether we're running mozinstall >=1.0... 17:16:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:16:47 INFO - Reading from file tmpfile_stdout 17:16:47 INFO - Output received: 17:16:47 INFO - Usage: mozinstall [options] installer 17:16:47 INFO - Options: 17:16:47 INFO - -h, --help show this help message and exit 17:16:47 INFO - -d DEST, --destination=DEST 17:16:47 INFO - Directory to install application into. [default: 17:16:47 INFO - "/builds/slave/test"] 17:16:47 INFO - --app=APP Application being installed. [default: firefox] 17:16:47 INFO - mkdir: /builds/slave/test/build/application 17:16:47 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'] 17:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:17:04 INFO - Reading from file tmpfile_stdout 17:17:04 INFO - Output received: 17:17:04 INFO - /builds/slave/test/build/application/firefox/firefox 17:17:04 INFO - Running post-action listener: _resource_record_post_action 17:17:04 INFO - [mozharness: 2017-09-02 00:17:04.023765Z] Finished install step (success) 17:17:04 INFO - [mozharness: 2017-09-02 00:17:04.024018Z] Running setup-mitmproxy step. 17:17:04 INFO - Running pre-action listener: _resource_record_pre_action 17:17:04 INFO - Running main action method: setup_mitmproxy 17:17:04 INFO - Skipping: mitmproxy is not required 17:17:04 INFO - Running post-action listener: _resource_record_post_action 17:17:04 INFO - [mozharness: 2017-09-02 00:17:04.025135Z] Finished setup-mitmproxy step (success) 17:17:04 INFO - [mozharness: 2017-09-02 00:17:04.025354Z] Running run-tests step. 17:17:04 INFO - Running pre-action listener: _resource_record_pre_action 17:17:04 INFO - Running pre-action listener: _set_gcov_prefix 17:17:04 INFO - Running main action method: run_tests 17:17:04 WARNING - Try message not found. 17:17:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:17:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:17:04 INFO - Python 2.7.3 17:17:04 INFO - Return code: 0 17:17:04 INFO - grabbing minidump binary from tooltool 17:17: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')]} 17:17:04 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a090>, '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 0xf8ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf84f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:17:04 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:17:04 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 17:17:04 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:17:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:17:04 INFO - Return code: 0 17:17:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:17:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:17:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:17:04 INFO - ENV: RUST_BACKTRACE is now full 17:17:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:17:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:17:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 17:17:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-039 --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 17:17:04 INFO - Using env: {'DISPLAY': ':0', 17:17:04 INFO - 'HOME': '/home/cltbld', 17:17:04 INFO - 'LANG': 'en_US.UTF-8', 17:17:04 INFO - 'LANGUAGE': 'en_US:en', 17:17:04 INFO - 'LOGNAME': 'cltbld', 17:17:04 INFO - 'MAIL': '/var/mail/cltbld', 17:17:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:17:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:17:04 INFO - 'MOZ_ACCELERATED': '1', 17:17:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:04 INFO - 'MOZ_NO_REMOTE': '1', 17:17:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:17:04 INFO - 'MOZ_WEBRENDER': '1', 17:17:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:04 INFO - 'NO_EM_RESTART': '1', 17:17:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:04 INFO - 'PWD': '/builds/slave/test', 17:17:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:17:04 INFO - 'RUST_BACKTRACE': 'full', 17:17:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:17:04 INFO - 'SHELL': '/bin/bash', 17:17:04 INFO - 'SHLVL': '1', 17:17:04 INFO - 'TERM': 'linux', 17:17:04 INFO - 'TMOUT': '86400', 17:17:04 INFO - 'USER': 'cltbld', 17:17:04 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', 17:17:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:04 INFO - '_': '/tools/buildbot/bin/python'} 17:17:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 17:17:04 INFO - mozversion application_buildid: 20170901232924 17:17:04 INFO - mozversion application_changeset: 5278dfcf5eb9f58eaf06ad1ce67e7fd4aba34772 17:17:04 INFO - mozversion application_display_name: Nightly 17:17:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:17:04 INFO - mozversion application_name: Firefox 17:17:04 INFO - mozversion application_remotingname: firefox 17:17:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:17:04 INFO - mozversion application_vendor: Mozilla 17:17:04 INFO - mozversion application_version: 57.0a1 17:17:04 INFO - mozversion platform_buildid: 20170901232924 17:17:04 INFO - mozversion platform_changeset: 5278dfcf5eb9f58eaf06ad1ce67e7fd4aba34772 17:17:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:17:04 INFO - mozversion platform_version: 57.0a1 17:17:04 INFO - using testdate: 1504311424 17:17:04 INFO - actual date: 1504311424 17:17:04 INFO - starting webserver on 'localhost:52339' 17:17:04 INFO - SUITE-START | Running 2 tests 17:17:04 INFO - TEST-START | dromaeo_css 17:17:04 INFO - Initialising browser for dromaeo_css test... 17:17:04 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52339/getInfo.html -profile /tmp/tmp8Bej2V/profile 17:17:04 INFO - TEST-INFO | started process 18095 (/builds/slave/test/build/application/firefox/firefox http://localhost:52339/getInfo.html) 17:17:12 INFO - TEST-INFO | 18095: exit 0 17:17:12 INFO - Browser initialized. 17:17:12 INFO - Running cycle 1/1 for dromaeo_css test... 17:17:12 INFO - Using env: {'DISPLAY': ':0', 17:17:12 INFO - 'HOME': '/home/cltbld', 17:17:12 INFO - 'JSGC_DISABLE_POISONING': '1', 17:17:12 INFO - 'LANG': 'en_US.UTF-8', 17:17:12 INFO - 'LANGUAGE': 'en_US:en', 17:17:12 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:17:12 INFO - 'LOGNAME': 'cltbld', 17:17:12 INFO - 'MAIL': '/var/mail/cltbld', 17:17:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:17:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:17:12 INFO - 'MOZ_ACCELERATED': '1', 17:17:12 INFO - 'MOZ_CRASHREPORTER': '1', 17:17:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:12 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:17:12 INFO - 'MOZ_NO_REMOTE': '1', 17:17:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:17:12 INFO - 'MOZ_WEBRENDER': '1', 17:17:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:12 INFO - 'NO_EM_RESTART': '1', 17:17:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:12 INFO - 'PWD': '/builds/slave/test', 17:17:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:17:12 INFO - 'RUST_BACKTRACE': 'full', 17:17:12 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:17:12 INFO - 'SHELL': '/bin/bash', 17:17:12 INFO - 'SHLVL': '1', 17:17:12 INFO - 'TERM': 'linux', 17:17:12 INFO - 'TMOUT': '86400', 17:17:12 INFO - 'USER': 'cltbld', 17:17:12 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', 17:17:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:12 INFO - '_': '/tools/buildbot/bin/python'} 17:17:12 INFO - TEST-INFO | started process 18288 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8Bej2V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:17:12 INFO - PID 18288 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:17:13 INFO - PID 18288 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:17:13 INFO - PID 18288 | 17:17:13 INFO - PID 18288 | (/builds/slave/test/build/application/firefox/firefox:18349): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:17:13 INFO - PID 18288 | 17:17:23 INFO - PID 18288 | RSS: Main: 210468864 17:17:23 INFO - PID 18288 | 17:17:28 INFO - PID 18288 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:20:23 INFO - PID 18288 | Cycle 1(1): loaded http://localhost:52339/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52339/tests/dromaeo/cssquery-ext.html) 17:20:24 INFO - PID 18288 | RSS: Main: 221261824 17:20:24 INFO - PID 18288 | 17:23:26 INFO - PID 18288 | Cycle 1(1): loaded http://localhost:52339/tests/dromaeo/cssquery-ext.html (next: http://localhost:52339/tests/dromaeo/cssquery-jquery.html) 17:23:27 INFO - PID 18288 | RSS: Main: 218079232 17:23:27 INFO - PID 18288 | 17:26:29 INFO - PID 18288 | Cycle 1(1): loaded http://localhost:52339/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52339/tests/dromaeo/cssquery-mootools.html) 17:26:29 INFO - PID 18288 | RSS: Main: 220446720 17:26:29 INFO - PID 18288 | 17:29:32 INFO - PID 18288 | Cycle 1(1): loaded http://localhost:52339/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52339/tests/dromaeo/cssquery-prototype.html) 17:29:32 INFO - PID 18288 | RSS: Main: 216514560 17:29:32 INFO - PID 18288 | 17:32:36 INFO - PID 18288 | Cycle 1(1): loaded http://localhost:52339/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52339/tests/dromaeo/cssquery-yui.html) 17:32:37 INFO - PID 18288 | RSS: Main: 222085120 17:32:37 INFO - PID 18288 | 17:35:39 INFO - PID 18288 | Cycle 1(1): loaded http://localhost:52339/tests/dromaeo/cssquery-yui.html (next: http://localhost:52339/tests/dromaeo/cssquery-dojo.html) 17:35:39 INFO - PID 18288 | RSS: Main: 224079872 17:35:39 INFO - PID 18288 | 17:35:39 INFO - PID 18288 | __start_tp_report 17:35:39 INFO - PID 18288 | _x_x_mozilla_page_load 17:35:39 INFO - PID 18288 | _x_x_mozilla_page_load_details 17:35:39 INFO - PID 18288 | |i|pagename|runs| 17:35:39 INFO - PID 18288 | |0;dojo.html;3340.599128104625;3361.2941282330708;3377.4504925863266;3396.8471418786166;3400.1669590950205;6436.259830119538;6471;6522.054302126189;6523.575967562111;6528.575642583235;6286.685665716719;6377.362263773632;6389.0416437534395;6398.936010639893;6413.13422687937;5814.069748840187;5830.621009634381;5833.125031245326;5859.53123750099;5903.321118071433;5876.294844618652;5890.764369425217;5927.37762534932;5930.347661757203;5939.316978547479;5852.180694702745;5894.941050589504;5903.4686878180955;5904.2029326040865;5947.821565353046;5855.648661080349;5876.29484461863;5905.350411454737;5916.230889984317;5918.674472903988;5806.477417032487;5854.824355269348;5881.500072493843;5960.374160713106;5980.1328807323125;6184.196054512884;6371.7769878054505;6391.616503009788;6537.771178008795;6538.803835884931;6852.6916288767125;6914.688839002257;6926.168859736814;6927.445804335641;6967.860642787166;6976.127984002;6990.091288132561;7022.789316320519;7028.578285302899;7030.367266945948;6404.103425520431;6409.8077057687415;6420.133282006916;6488.124103246048;6502.057201705727;6870.381665650116;7016.92983070173;7048.224695283515;7074.151101867808;7087.0786797717;6602.372774586503;6609.074729537869;6611.041398997215;6613.4709223262025;6616.007598860209;6636.30318816527;6712.630805305755;6862.897056544156;6872.587644741231;6972.616506092214;6450.608272506082;6886.621235831977;6896.517243792886;6984.61584612841;7053.259407762213;6916.1700595927805;6935.445164386837;7036.296370363025;7049.541779784297;7049.647517624098;5296.443873393316;5321.627486075938;5325.201219817059;5343.412224655285;5465.781368745285;4724.196886529298;4732.810687572459;4744;4745.667803253808;4753.358296629911;5919.526437884959;5921;5946.643201407929;5966.791162309299;5972.313183983811;6869.0383346331555;6878.58728476283;6892.276310987374;6913.1358580177475;7005.964970175117;5902.940970590325;5970.970145149333;6038.849028774228;6073.423024812636;6116.571839971249;5747.339056008613;5767.509761670221;5770.2498675171955;5782.074868021096;5788.652680839079;6588.275289718127;6606.504512161511;6619;6645.501587380869;6652.434543063894;6723.5293529452465;6782.762603308861;6810.353016463428;6836.794896153124;6876.106106206161;6609.537332386687;6700.564463309869;6734.326567343226;6739.93260067393;6814;6824.522283440111;6855.5543889647015;6884.51808373409;6953.860922781617;6972.1981972071835;6735.494837887276;6853.451723862178;6859.862802743817;6864.965675171592;7090.539114957512;6568.770093046721;6604.5046621502615;6624.668766561749;6682.599044057372;6742.898856517058;6736.932630673827;6781.11845460087;6781.864362712619;6782.457403407814;6804.455643548603;6683.298253683488;6704.061431399706;6725.630090345078;6805.149356330458;6811.659417029228;6762.357576030269;6786.423154031962;6792;6835.58986460794;6871.072405225231;6283.7800676976085;6418.069379939976;6443.581167224115;6450.161479007699;6468.935310646833 17:35:39 INFO - PID 18288 | |1;ext.html;817.9795505112371;828.9709860154888;861.8362511122887;873.4409977614328;888.5157589113933;41946;42513;42686;42719;42830.14339713212;38527.614723852756;38748.22503549927;38803.41794873079;39053.609463905355;39108.80445597775;6970.128733908262;7071.398931090837;7085.751998680047;7093.290670932917;7097.8225544361285;6931.584104953694;6959.8956015659805;7008.264132266115;7022.332878376572;7026.191987921404;6951.89572156418;6989.4408447324095;7031.332023457763;7285.672144753499;7975.840483190333;7849.175836537139;7891.4081443891655;7911.090224624127;7920.089189743196;7974.202579742037;7701.807454813618;7749.341305988998;7770.57261850598;7792.961035194844;7795.415343849234;38846;39282.60717392818;39337.40993885065;39525.40711889324;39706.20587588232;3618.0412190769466;3627.818609069536;3633.7819730816236;3650.7809531428197;3667.1382225177063;31953.201169970704;32118.197045073826;32197.03408897737;32285.35429291401;32563.02310930676;5125.8718532037;5196.974015129938;5224.059669259532;5241.947580524184;5287.41838397776;183820;185266;185321;190250;201609;48051;48522;48531.514684853406;48712.51287487151;48938;50328.24507632388;50846.49153508492;50983.980320393384;51317.23024154641;51436.485635143916;14645.48740794067;14912.105273683614;14955.626109347353;14983.101013939198;15204.46784362542;12358.073144514197;12374.75250494985;12401.6279511613;12421.75156496865;12428;13758.174509529461;13771.931140344435;13787.172769633855;13824.308784560933;13964.092333998258;5615.157726341082;5617.466340697627;5724.542036637059;5756.222909907109;5803.187553742479;1010.3382284603597;1013.6705570689555;1021.836506159011;1034.0693375961694;1039.1323245090282;23100.768992310197;23107.422314442272;23108.65337019946;23201.88399057994;23601.645975310385;1107.684309971654;1131.9207655464204;1133.5295852221236;1138.3795831271977;1141.6175581180398;11966.700832479084;12192.39038048094;12268.141230113806;12288.508459661693;12291.016718662486;224229;225140;226059;226250;226493;52626;52912.20631690528;53059;53216.46783532193;53557;1427.093795439903;1450.0719539494733;1461.1598330959528;1461.1598330959741;1480.000999325451;22258.443538911655;22893.08427662875;22945.196918107787;23227.3031809046;23504.177353792536;38141;38146.809265953496;38223.23553528969;38231.04422389463;38250.80874595609;191472;191957;193256;193429;193942;30731;33248.501272481415;33716.83141584276;34636.65363346334;35231.82384088063;44144.11711765811;44301;44471;44643.33035004543;45444.318335224336;31753.206169845016;31918;32154.51768223525;32411.675883240867;32544.83727581347;32783.83608081944;33261.00216993494;33955.83022084874;34476.13809654678;34547.48178777371;37467.43798843074;38408.231835362574;38627.420588691755;38768;38776.2244755109;32504.34991300208;32957.34085318328;33372;33416.498752518244;33574.32851343008;23447.531049378576;23692;24022.27933162008;24329.51340973206;24468.755312446647 17:35:39 INFO - PID 18288 | |2;jquery.html;569.5073761196563;570.8601392658796;571.8456016875441;598.5540772124766;599.6042611876161;21616.783832161695;22339.553208935813;22364.552708945812;22381.10475580975;22429.43926401841;23259.76740232597;25287.2413827585;25429.618555721678;25497;25559.744402555967;6688.93311066888;6757.222919364287;6795.728170873159;6860.34826691466;6866.485013623973;6145.385461453863;6674.833129171785;6778.49161312901;6783.389494945454;6799.898001529957;6679.766208182715;6700.095487109251;6702.463802895757;6702.7654032108885;6710.83222919426;6633.436157926582;6659.433948114416;6694.93305066948;6698.56459330142;6705.2624211336715;6191.814245572595;6197.566170368076;6200.844978875519;6204.6587437690905;6221.440070393686;28608.856955715295;28895.42209155805;29542.261443464085;29557;30390.54414183789;1972.4378552112723;2011.7585889693185;2017.4552870724822;2022.0395312226722;2026.493376655836;19195.616087678307;19539.90230048855;19616.80383196164;19642;19661;2392.7726865947707;2416.492536567323;2493.47636996231;2495.4011037350906;2508.8871000804825;132208;137958.62041379558;138008;138505;138810.3059484696;14107.576772696835;14585.635359116108;14676.3395647194;14749;14901.403943842342;23044.308670739905;23190;23190.42023949381;23203.303900883002;23215.187468438522;8948.060453652402;9308.488033158106;9357.298202634829;9406.153446189875;9483.383580067273;8340.916590834135;8364;8487.405881588353;8500.10748871372;8688.218060374596;8889.48865198256;8899.15458031486;9016.549172541347;9055.637774489078;9351.251899847995;2683.87328761994;2702.8243164194264;2776.1255204610484;2813.9015134470196;2852.6434195725533;937.4000639590668;970.4371464550544;995.3032876986139;998.2513115163628;1002.2883752535636;19851.30520431778;19915.943736210953;20063.39809805708;20233.291834785712;20480.078396472196;1023.7696518283476;1037.4605205293205;1039.1998161415818;1050.553514756241;1051.332403923514;11080.556777728962;11202;11229.382383968798;11300.434978251054;11301.717457063474;436880;444200;445163;452644;453014.2047869285;112327;115776;116476;116491;118547;1131.3664347965166;1157.6584907452323;1170.7307319316435;1176.147293212414;1180.2682336951145;17900.462986110437;17964;18100.18549165317;18381.17284722164;18462.35381761632;53347.19979200237;54752.262386881295;54982.72508637431;55108.897822042534;55534;67001.66499167473;67385.66307168432;67750.3224967744;68213.65893170502;68669.31330686629;12182.269063856196;14410.42358305656;14657.120572765669;14752.262386880398;14812.555623331318;17107.91446042762;18401.171947262672;18794.15426305792;18984.43046708601;23991;18482.907585461988;18720.344787932358;18764.249430022646;18966.905165474083;19016.334428295497;16193.433229836899;16212.594685132966;17408.47774566765;17568.2094305754;18052.72920906161;17780.91109544444;18808.905955470134;18860.434186974413;18870.622587548445;19076.809231907504;14045.367958442099;14506.1296322221;14546.709065818413;14679.19264440466;14756.778648320069;17172.39896603613;17485.737713934046;17496.91251543734;18500.444986650422;18502.907485462485 17:35:39 INFO - PID 18288 | |3;mootools.html;1340.162398500937;1360.476216656587;1384.114166933163;1392.5752645443135;1397.4829313154135;14819.851801481982;15398.769018464718;15442.382304707813;15511;15525.068495890255;14862.925685371558;15485.83856210783;15735.84264157361;15768.921155394208;15792.289346979385;2627.684677838662;2724.50958827411;2724.8637568121517;2727.2227415186676;2728.7134850840675;2623.855687937172;2672.3987102901788;2674.4651069786023;2676.1302576662565;2676.397810492633;2528.469021505486;2753.4768394005173;2753.8347699138017;2757.7931655125844;2759.751622353988;2381.2618088392655;2408.205292253552;2469.6789417375717;2497.988314732527;2526.393665520271;2590.2617753940035;2646.2458199413077;2651.6287719719253;2652.8010399220134;2658.8005899557415;14812.851871481254;14973.326200321011;15119.39522419102;15140.167290799001;15148.469803556933;7440.404767618577;7694.269044440769;7700.306972372513;7700.730474433424;7721.806954826175;4829.299751536043;4902.28916807061;4916;5036.043151801183;5055.191169412913;1893.697008478637;1914.2917120665443;1924.3457224543722;1960;1982.5935683184912;26102.477950441076;26235.081772138074;26269.868650656623;26379.73620263792;26395.284306520014;6489.67551622417;6632.6352050636715;6669.766558170538;6750.797476075725;6874.209465911485;17250.309987600605;17324.740128898076;17423.91288043577;17452.301907923542;17475.47573572795;7724.0731112266885;7876.133625301212;7922.366210703245;7991.72028978983;7992.720254791171;7001.964990175016;7029.9648501757165;7078.787636370917;7092.5389849659605;7140.7857764267155;4835.371401717754;5002.624803139779;5035.748212589356;5086.847394578094;5110.872228194325;1376.2636989210723;1381.1505923856957;1415.5399495164113;1427.293489722594;1443.6896067345572;900.4732231644562;901.9143181397887;916.6562539047857;934.9298802589833;936.7892224249489;8252.958735206286;8280.171982801672;8286.171382861665;8320.209580089882;8427.452215605967;715.5670819154441;716.7813816785826;718.2350796401815;720.3121019426435;782.7808213700173;5336.626436149432;5344.118220493653;5594.300712410949;5655.519280861173;5708.086706127;20774.85738284409;21392.572148557254;21475.78524214738;21719.348419547438;21992.78007219939;20845.58308833815;21037.789622103584;21927.890360548095;21934;21984.340469785933;10054.396736195851;10270.664813574187;10600.522976466078;10620.681379558471;10635.893641063645;8590.484570925515;8614.78463038429;8634.30925525969;8730.869036964577;8814.779630509289;18498.16758245855;18602.34891778781;18906.14922328526;19113.90443047776;19159.233630654617;18787.24851005999;18881.90559047196;19458.8054119457;19583.314583989493;19928.50178745543;11037.779244415227;11251.606193783506;11260.831087533857;11285.830712539482;12065.215760975509;13995.650108747363;14265.144091354552;14278.2146981917;14284.71430571362;14311.713765724835;10904.890951090387;10981.176411768989;11172.664820055412;11229.3262404256;11459.828102578635;10472.842907356244;10804.135669146606;10827.621033263798;11015;11118.166137539554;10252.179825613784;10319.432431216055;10327.225458090823;10395.480225988822;10461.633842815465;6845.760398386033;6892.655367231719;6933.1333583302085;6947.131608548932;7151.106111736033;7338.853222935618;7349.338559529668;7408.814779630552;7427.517211381243;7474.177840437439 17:35:39 INFO - PID 18288 | |4;prototype.html;503.36827281761384;508.32646743065436;531.619891777379;534.3774502704349;535.3484500316521;6333.493320534358;6348.36516348365;6382.212980365493;6412.967935160318;6438.871222575557;6241.687915604224;6254.812355629327;6356.237251529811;6359.138387725518;6365.681715914197;5189.299444574972;5251.842444726664;5254.343207099113;5276.445973172819;5285.2600635911;5222.8694282642855;5248.134057880445;5269.314989051418;5288.338957630296;5314.681119132864;4921.63087768417;4973.825916092937;5048.29323894655;5194.805194805197;5205.843824685247;5001.674891132083;5003.349564556621;5043.2435134730085;5050.772715227824;5088.109580823341;4429.579189976947;4459.531749166355;4465.330200469923;4466.240739074366;4486.7083639563325;6098.451139397431;6177.7837775677635;6199.380061993809;6270.623762574261;6277.529185311074;5698.743556539925;5730.882477916808;5733.397993210736;5736.770529178828;5782.797602083907;6699.933000670028;6740.191177058784;6747.291534388867;6758.695858686371;6790.32096790322;4864.9053962858425;4872.512748725135;4938.9753051234875;4945.67853089548;4951.257311403318;6774.796756097325;6913.274106218875;6928.930710692828;7002.649867506655;7151.284871512859;4851.902961940741;4879.194932836113;4907.656464047483;4948.752562371867;4950.727709975914;5052.898942021139;5056.544910958031;5069.6451248413;5074.873128171751;5091.898162036812;5655.858603534944;5684.175794509773;5712.6286791358425;5724.370319264877;5738.8278351649515;6548.967255163694;6673.698628767441;6684.231313398924;6713.731450741916;6721.395074443323;1755.5347832824057;1758.384565402099;1765.7792775903013;1768.9823101768818;1779.7063484525167;1009.2733232072895;1195.1932445599186;1214.6052532926833;1223;1245.9314737689333;5827.796027139115;5829.446202610745;5845.357010728816;5893.14549390336;5917.55618328627;6729.629870357177;6869.965650171717;6876.040328506352;6892.896606550906;6960.512764106464;5821.214136091573;5867.70661466925;5916.615419997686;5919.668074683162;5932.110183472513;3646.124930016777;3655.3420384330543;3713.1830997180577;3716.2939041582554;3727.366347720894;6526.902096468557;6557.934420655828;6584.76953306632;6656.30108838565;6673.995602638378;6986.580805151605;7001;7007.684654190573;7048.92951070493;7099.503034787515;6583.934160658428;6623.43700785429;6663.567797753882;6666.633335166613;6755.3582409671;6757;6869.450443964571;6888.483363747639;6891.689873955584;6921.48088893325;6135.539834512519;6742.730290788313;6786.21958474762;6812.863742725019;6889.138857642794;6539.836504087436;6571.737130514726;6587.440067594308;6642.368974947372;6715.26132125456;5018.698878067327;5020.297158397901;5025.698458092526;5042.722650254272;5065.620078494054;6646.534742567974;6932.301586111435;6947.3747362737595;6974.302569743188;6980.720771169096;6312.337204593451;6573.96713016451;6633.867322653617;6651.572176155765;6709.429698475684;6589.4069533742195;6630.30381809903;6634.203895532374;6643.46852251809;6662.367075127855;6388.169537960035;6423.96788016057;6532.8366790830605;6567.835804104936;6638.5684930479365;6530.453123281829;6643.269240383457;6671.8665626686225;6683.43190828785;6704.229013663491;6366.713497892699;6369.254905095733;6498.447631951337;6504.15445992018;6521.673916304071 17:35:39 INFO - PID 18288 | |5;yui.html;853.8804567360567;855.6705668317703;877.6533269358604;918.3433844800968;929.6932012435897;17347.47957561272;17897.10514474276;17904.731429028576;17957.820421795794;19781;18652.720209196872;18786.624267514642;18822.811771882276;18907.24371025161;18989.90505047473;717.720089165226;739.5119221313934;967.743547959793;977.237754551451;983.6360546597768;784.8979632647777;971.6696323250094;996.217968894418;996.7358649957768;997.7605374710054;1048.821700310945;1051.4637534857202;1052.3896140238644;1053.9420331881743;1058.0847566854654;1033.6640591807654;1045.6549338718205;1047.314009323894;1047.7171163785736;1050.8266136087575;766.1380946435261;767.106321135876;769.5190505933791;770.4760762681375;771.2865599320662;17751.556211094696;17945.641087178316;18020.63958720818;18120.818791812046;19551.90224048885;20170.294039708686;20878.5824283515;21071.68392474114;21392.89303553488;21586.89206553957;15652.843471565366;15806.288717007763;16239.51281461558;16408.83591164085;16456.259468323955;3660.890173294805;3663.139162296858;3684.078980254936;3716.535433070866;3717.739758216926;118951;127272;127674;127929;128892.3555382217;16015.439459618858;16376.754348684779;16404.507864764077;16498.257578409;16638.3344666212;30067.849660751555;30282;30320.393592128035;30372.544411833842;30375;8083.595820208966;8112.067112282163;8146.551939643258;8150.470219435718;8162.959185204155;3601.4777857210556;3763.6236376362144;4004.0990777074926;4083.959160408358;4185.037441388497;5032.421271553745;5116.155834287301;5138.588912887035;5156.432792392834;5171.146760784503;964.9758756031156;982.3221976836103;991.7570195302197;992.4045572656348;994.8408254679216;681.6830173969029;687.3161204601354;702.8207807009283;708.5111273221461;713.6717110129399;11349.432528373549;11661.6501504955;12173.817392739116;12805.487780488862;12960.092793504553;690.2545251128769;694.9200841903145;697.8185671725388;710.3216428311054;712.6579241963783;5220.1647736362;5591.161325801081;5672.347680016768;5711.286089238845;5923.318818335946;106120;110237;110488;112019.43990279996;113173.43413283046;96379.51810241044;97249.28395407274;98861;99798;99974.50012749889;80093.19906800974;83928.16071839203;84701.57649211715;84815.72776408358;86611.1338886603;10129.290949633454;10161.034701703325;10221.33561318512;10297.485125743833;10383.48082595852;40688;41039.794801027;41469;42087.158256835304;42431.575684242765;52406.21390679067;52572.73713631407;53444.73277633587;53819.46180538145;54097.45902540924;15159.317830697866;15817.446389376317;15965.28156232949;16018.119003454922;16082.276297566403;17297.308107676054;17346.04596747191;17392.47822565325;17494.650106998044;17673.82326176722;15746.527604171893;15876.682466350378;15920.124393158025;16092.436764713178;16164.919175404048;14923.104613723212;14980.700385992;14997.400103995718;15207.011544249588;15237;14331.856681433052;14656.853431465548;16372.181390930644;16730.916345418194;16912;11590.478428470908;12161.33112678811;12274.07944404191;12395.876041239833;13569.932150339186;13458.730825383242;13827.446902123802;13956;14515.491957781427;14540.85459145395 17:35:39 INFO - PID 18288 | __end_tp_report 17:35:39 INFO - PID 18288 | __start_cc_report 17:35:39 INFO - PID 18288 | _x_x_mozilla_cycle_collect,356 17:35:39 INFO - PID 18288 | __end_cc_report 17:35:39 INFO - PID 18288 | __startTimestamp1504312539451__endTimestamp 17:35:39 INFO - PID 18288 | 17:35:39 INFO - PID 18288 | ------- Summary: start ------- 17:35:39 INFO - PID 18288 | Number of tests: 6 17:35:39 INFO - PID 18288 | 17:35:39 INFO - PID 18288 | [#0] dojo.html Cycles:165 Average:6327.99 Median:6588.28 stddev:748.81 (11.4%) stddev-sans-first:713.49 17:35:39 INFO - PID 18288 | Values: 3340.6 3361.3 3377.5 3396.8 3400.2 6436.3 6471.0 6522.1 6523.6 6528.6 6286.7 6377.4 6389.0 6398.9 6413.1 5814.1 5830.6 5833.1 5859.5 5903.3 5876.3 5890.8 5927.4 5930.3 5939.3 5852.2 5894.9 5903.5 5904.2 5947.8 5855.6 5876.3 5905.4 5916.2 5918.7 5806.5 5854.8 5881.5 5960.4 5980.1 6184.2 6371.8 6391.6 6537.8 6538.8 6852.7 6914.7 6926.2 6927.4 6967.9 6976.1 6990.1 7022.8 7028.6 7030.4 6404.1 6409.8 6420.1 6488.1 6502.1 6870.4 7016.9 7048.2 7074.2 7087.1 6602.4 6609.1 6611.0 6613.5 6616.0 6636.3 6712.6 6862.9 6872.6 6972.6 6450.6 6886.6 6896.5 6984.6 7053.3 6916.2 6935.4 7036.3 7049.5 7049.6 5296.4 5321.6 5325.2 5343.4 5465.8 4724.2 4732.8 4744.0 4745.7 4753.4 5919.5 5921.0 5946.6 5966.8 5972.3 6869.0 6878.6 6892.3 6913.1 7006.0 5902.9 5971.0 6038.8 6073.4 6116.6 5747.3 5767.5 5770.2 5782.1 5788.7 6588.3 6606.5 6619.0 6645.5 6652.4 6723.5 6782.8 6810.4 6836.8 6876.1 6609.5 6700.6 6734.3 6739.9 6814.0 6824.5 6855.6 6884.5 6953.9 6972.2 6735.5 6853.5 6859.9 6865.0 7090.5 6568.8 6604.5 6624.7 6682.6 6742.9 6736.9 6781.1 6781.9 6782.5 6804.5 6683.3 6704.1 6725.6 6805.1 6811.7 6762.4 6786.4 6792.0 6835.6 6871.1 6283.8 6418.1 6443.6 6450.2 6468.9 17:35:39 INFO - PID 18288 | 17:35:39 INFO - PID 18288 | [#1] ext.html Cycles:180 Average:37406.65 Median:3622.93 stddev:52664.36 (1453.6%) stddev-sans-first:52740.43 17:35:39 INFO - PID 18288 | Values: 818.0 829.0 861.8 873.4 888.5 41946.0 42513.0 42686.0 42719.0 42830.1 38527.6 38748.2 38803.4 39053.6 39108.8 6970.1 7071.4 7085.8 7093.3 7097.8 6931.6 6959.9 7008.3 7022.3 7026.2 6951.9 6989.4 7031.3 7285.7 7975.8 7849.2 7891.4 7911.1 7920.1 7974.2 7701.8 7749.3 7770.6 7793.0 7795.4 38846.0 39282.6 39337.4 39525.4 39706.2 3618.0 3627.8 3633.8 3650.8 3667.1 31953.2 32118.2 32197.0 32285.4 32563.0 5125.9 5197.0 5224.1 5241.9 5287.4 183820.0 185266.0 185321.0 190250.0 201609.0 48051.0 48522.0 48531.5 48712.5 48938.0 50328.2 50846.5 50984.0 51317.2 51436.5 14645.5 14912.1 14955.6 14983.1 15204.5 12358.1 12374.8 12401.6 12421.8 12428.0 13758.2 13771.9 13787.2 13824.3 13964.1 5615.2 5617.5 5724.5 5756.2 5803.2 1010.3 1013.7 1021.8 1034.1 1039.1 23100.8 23107.4 23108.7 23201.9 23601.6 1107.7 1131.9 1133.5 1138.4 1141.6 11966.7 12192.4 12268.1 12288.5 12291.0 224229.0 225140.0 226059.0 226250.0 226493.0 52626.0 52912.2 53059.0 53216.5 53557.0 1427.1 1450.1 1461.2 1461.2 1480.0 22258.4 22893.1 22945.2 23227.3 23504.2 38141.0 38146.8 38223.2 38231.0 38250.8 191472.0 191957.0 193256.0 193429.0 193942.0 30731.0 33248.5 33716.8 34636.7 35231.8 44144.1 44301.0 44471.0 44643.3 45444.3 31753.2 31918.0 32154.5 32411.7 32544.8 32783.8 33261.0 33955.8 34476.1 34547.5 37467.4 38408.2 38627.4 38768.0 38776.2 32504.3 32957.3 33372.0 33416.5 33574.3 23447.5 23692.0 24022.3 24329.5 24468.8 17:35:39 INFO - PID 18288 | 17:35:39 INFO - PID 18288 | [#2] jquery.html Cycles:180 Average:33242.43 Median:23117.15 stddev:75840.66 (328.1%) stddev-sans-first:76013.74 17:35:39 INFO - PID 18288 | Values: 569.5 570.9 571.8 598.6 599.6 21616.8 22339.6 22364.6 22381.1 22429.4 23259.8 25287.2 25429.6 25497.0 25559.7 6688.9 6757.2 6795.7 6860.3 6866.5 6145.4 6674.8 6778.5 6783.4 6799.9 6679.8 6700.1 6702.5 6702.8 6710.8 6633.4 6659.4 6694.9 6698.6 6705.3 6191.8 6197.6 6200.8 6204.7 6221.4 28608.9 28895.4 29542.3 29557.0 30390.5 1972.4 2011.8 2017.5 2022.0 2026.5 19195.6 19539.9 19616.8 19642.0 19661.0 2392.8 2416.5 2493.5 2495.4 2508.9 132208.0 137958.6 138008.0 138505.0 138810.3 14107.6 14585.6 14676.3 14749.0 14901.4 23044.3 23190.0 23190.4 23203.3 23215.2 8948.1 9308.5 9357.3 9406.2 9483.4 8340.9 8364.0 8487.4 8500.1 8688.2 8889.5 8899.2 9016.5 9055.6 9351.3 2683.9 2702.8 2776.1 2813.9 2852.6 937.4 970.4 995.3 998.3 1002.3 19851.3 19915.9 20063.4 20233.3 20480.1 1023.8 1037.5 1039.2 1050.6 1051.3 11080.6 11202.0 11229.4 11300.4 11301.7 436880.0 444200.0 445163.0 452644.0 453014.2 112327.0 115776.0 116476.0 116491.0 118547.0 1131.4 1157.7 1170.7 1176.1 1180.3 17900.5 17964.0 18100.2 18381.2 18462.4 53347.2 54752.3 54982.7 55108.9 55534.0 67001.7 67385.7 67750.3 68213.7 68669.3 12182.3 14410.4 14657.1 14752.3 14812.6 17107.9 18401.2 18794.2 18984.4 23991.0 18482.9 18720.3 18764.2 18966.9 19016.3 16193.4 16212.6 17408.5 17568.2 18052.7 17780.9 18808.9 18860.4 18870.6 19076.8 14045.4 14506.1 14546.7 14679.2 14756.8 17172.4 17485.7 17496.9 18500.4 18502.9 17:35:39 INFO - PID 18288 | 17:35:39 INFO - PID 18288 | [#3] mootools.html Cycles:180 Average:9301.32 Median:2559.37 stddev:6713.43 (262.3%) stddev-sans-first:6705.62 17:35:39 INFO - PID 18288 | Values: 1340.2 1360.5 1384.1 1392.6 1397.5 14819.9 15398.8 15442.4 15511.0 15525.1 14862.9 15485.8 15735.8 15768.9 15792.3 2627.7 2724.5 2724.9 2727.2 2728.7 2623.9 2672.4 2674.5 2676.1 2676.4 2528.5 2753.5 2753.8 2757.8 2759.8 2381.3 2408.2 2469.7 2498.0 2526.4 2590.3 2646.2 2651.6 2652.8 2658.8 14812.9 14973.3 15119.4 15140.2 15148.5 7440.4 7694.3 7700.3 7700.7 7721.8 4829.3 4902.3 4916.0 5036.0 5055.2 1893.7 1914.3 1924.3 1960.0 1982.6 26102.5 26235.1 26269.9 26379.7 26395.3 6489.7 6632.6 6669.8 6750.8 6874.2 17250.3 17324.7 17423.9 17452.3 17475.5 7724.1 7876.1 7922.4 7991.7 7992.7 7002.0 7030.0 7078.8 7092.5 7140.8 4835.4 5002.6 5035.7 5086.8 5110.9 1376.3 1381.2 1415.5 1427.3 1443.7 900.5 901.9 916.7 934.9 936.8 8253.0 8280.2 8286.2 8320.2 8427.5 715.6 716.8 718.2 720.3 782.8 5336.6 5344.1 5594.3 5655.5 5708.1 20774.9 21392.6 21475.8 21719.3 21992.8 20845.6 21037.8 21927.9 21934.0 21984.3 10054.4 10270.7 10600.5 10620.7 10635.9 8590.5 8614.8 8634.3 8730.9 8814.8 18498.2 18602.3 18906.1 19113.9 19159.2 18787.2 18881.9 19458.8 19583.3 19928.5 11037.8 11251.6 11260.8 11285.8 12065.2 13995.7 14265.1 14278.2 14284.7 14311.7 10904.9 10981.2 11172.7 11229.3 11459.8 10472.8 10804.1 10827.6 11015.0 11118.2 10252.2 10319.4 10327.2 10395.5 10461.6 6845.8 6892.7 6933.1 6947.1 7151.1 7338.9 7349.3 7408.8 7427.5 7474.2 17:35:39 INFO - PID 18288 | 17:35:39 INFO - PID 18288 | [#4] prototype.html Cycles:180 Average:5601.54 Median:6262.72 stddev:1580.46 (25.2%) stddev-sans-first:1537.87 17:35:39 INFO - PID 18288 | Values: 503.4 508.3 531.6 534.4 535.3 6333.5 6348.4 6382.2 6413.0 6438.9 6241.7 6254.8 6356.2 6359.1 6365.7 5189.3 5251.8 5254.3 5276.4 5285.3 5222.9 5248.1 5269.3 5288.3 5314.7 4921.6 4973.8 5048.3 5194.8 5205.8 5001.7 5003.3 5043.2 5050.8 5088.1 4429.6 4459.5 4465.3 4466.2 4486.7 6098.5 6177.8 6199.4 6270.6 6277.5 5698.7 5730.9 5733.4 5736.8 5782.8 6699.9 6740.2 6747.3 6758.7 6790.3 4864.9 4872.5 4939.0 4945.7 4951.3 6774.8 6913.3 6928.9 7002.6 7151.3 4851.9 4879.2 4907.7 4948.8 4950.7 5052.9 5056.5 5069.6 5074.9 5091.9 5655.9 5684.2 5712.6 5724.4 5738.8 6549.0 6673.7 6684.2 6713.7 6721.4 1755.5 1758.4 1765.8 1769.0 1779.7 1009.3 1195.2 1214.6 1223.0 1245.9 5827.8 5829.4 5845.4 5893.1 5917.6 6729.6 6870.0 6876.0 6892.9 6960.5 5821.2 5867.7 5916.6 5919.7 5932.1 3646.1 3655.3 3713.2 3716.3 3727.4 6526.9 6557.9 6584.8 6656.3 6674.0 6986.6 7001.0 7007.7 7048.9 7099.5 6583.9 6623.4 6663.6 6666.6 6755.4 6757.0 6869.5 6888.5 6891.7 6921.5 6135.5 6742.7 6786.2 6812.9 6889.1 6539.8 6571.7 6587.4 6642.4 6715.3 5018.7 5020.3 5025.7 5042.7 5065.6 6646.5 6932.3 6947.4 6974.3 6980.7 6312.3 6574.0 6633.9 6651.6 6709.4 6589.4 6630.3 6634.2 6643.5 6662.4 6388.2 6424.0 6532.8 6567.8 6638.6 6530.5 6643.3 6671.9 6683.4 6704.2 6366.7 6369.3 6498.4 6504.2 6521.7 17:35:39 INFO - PID 18288 | 17:35:39 INFO - PID 18288 | [#5] yui.html Cycles:180 Average:22676.70 Median:20524.44 stddev:31664.36 (154.3%) stddev-sans-first:31710.79 17:35:39 INFO - PID 18288 | Values: 853.9 855.7 877.7 918.3 929.7 17347.5 17897.1 17904.7 17957.8 19781.0 18652.7 18786.6 18822.8 18907.2 18989.9 717.7 739.5 967.7 977.2 983.6 784.9 971.7 996.2 996.7 997.8 1048.8 1051.5 1052.4 1053.9 1058.1 1033.7 1045.7 1047.3 1047.7 1050.8 766.1 767.1 769.5 770.5 771.3 17751.6 17945.6 18020.6 18120.8 19551.9 20170.3 20878.6 21071.7 21392.9 21586.9 15652.8 15806.3 16239.5 16408.8 16456.3 3660.9 3663.1 3684.1 3716.5 3717.7 118951.0 127272.0 127674.0 127929.0 128892.4 16015.4 16376.8 16404.5 16498.3 16638.3 30067.8 30282.0 30320.4 30372.5 30375.0 8083.6 8112.1 8146.6 8150.5 8163.0 3601.5 3763.6 4004.1 4084.0 4185.0 5032.4 5116.2 5138.6 5156.4 5171.1 965.0 982.3 991.8 992.4 994.8 681.7 687.3 702.8 708.5 713.7 11349.4 11661.7 12173.8 12805.5 12960.1 690.3 694.9 697.8 710.3 712.7 5220.2 5591.2 5672.3 5711.3 5923.3 106120.0 110237.0 110488.0 112019.4 113173.4 96379.5 97249.3 98861.0 99798.0 99974.5 80093.2 83928.2 84701.6 84815.7 86611.1 10129.3 10161.0 10221.3 10297.5 10383.5 40688.0 41039.8 41469.0 42087.2 42431.6 52406.2 52572.7 53444.7 53819.5 54097.5 15159.3 15817.4 15965.3 16018.1 16082.3 17297.3 17346.0 17392.5 17494.7 17673.8 15746.5 15876.7 15920.1 16092.4 16164.9 14923.1 14980.7 14997.4 15207.0 15237.0 14331.9 14656.9 16372.2 16730.9 16912.0 11590.5 12161.3 12274.1 12395.9 13569.9 13458.7 13827.4 13956.0 14515.5 14540.9 17:35:39 INFO - PID 18288 | -------- Summary: end -------- 17:35:39 INFO - PID 18288 | 17:35:40 INFO - TEST-INFO | 18288: exit 0 17:35:40 INFO - TEST-OK | dromaeo_css | took 1116019ms 17:35:40 INFO - TEST-START | kraken 17:35:40 INFO - Initialising browser for kraken test... 17:35:40 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52339/getInfo.html -profile /tmp/tmpkcCuKS/profile 17:35:40 INFO - TEST-INFO | started process 18675 (/builds/slave/test/build/application/firefox/firefox http://localhost:52339/getInfo.html) 17:35:46 INFO - TEST-INFO | 18675: exit 0 17:35:46 INFO - Browser initialized. 17:35:46 INFO - Running cycle 1/1 for kraken test... 17:35:46 INFO - Using env: {'DISPLAY': ':0', 17:35:46 INFO - 'HOME': '/home/cltbld', 17:35:46 INFO - 'JSGC_DISABLE_POISONING': '1', 17:35:46 INFO - 'LANG': 'en_US.UTF-8', 17:35:46 INFO - 'LANGUAGE': 'en_US:en', 17:35:46 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:35:46 INFO - 'LOGNAME': 'cltbld', 17:35:46 INFO - 'MAIL': '/var/mail/cltbld', 17:35:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:35:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:35:46 INFO - 'MOZ_ACCELERATED': '1', 17:35:46 INFO - 'MOZ_CRASHREPORTER': '1', 17:35:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:46 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:35:46 INFO - 'MOZ_NO_REMOTE': '1', 17:35:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:35:46 INFO - 'MOZ_WEBRENDER': '1', 17:35:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:35:46 INFO - 'NO_EM_RESTART': '1', 17:35:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:35:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:46 INFO - 'PWD': '/builds/slave/test', 17:35:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:35:46 INFO - 'RUST_BACKTRACE': 'full', 17:35:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:35:46 INFO - 'SHELL': '/bin/bash', 17:35:46 INFO - 'SHLVL': '1', 17:35:46 INFO - 'TERM': 'linux', 17:35:46 INFO - 'TMOUT': '86400', 17:35:46 INFO - 'USER': 'cltbld', 17:35:46 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985', 17:35:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:46 INFO - '_': '/tools/buildbot/bin/python'} 17:35:46 INFO - TEST-INFO | started process 18870 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkcCuKS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:35:47 INFO - PID 18870 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:35:47 INFO - PID 18870 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:35:48 INFO - PID 18870 | 17:35:48 INFO - PID 18870 | (/builds/slave/test/build/application/firefox/firefox:18932): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:35:48 INFO - PID 18870 | 17:35:58 INFO - PID 18870 | RSS: Main: 206815232 17:35:58 INFO - PID 18870 | 17:36:01 INFO - PID 18870 | Cycle 1(1): loaded http://localhost:52339/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52339/tests/kraken/driver.html?testName=audio-beat-detection) 17:36:02 INFO - PID 18870 | RSS: Main: 212955136 17:36:02 INFO - PID 18870 | 17:36:04 INFO - PID 18870 | Cycle 1(1): loaded http://localhost:52339/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52339/tests/kraken/driver.html?testName=audio-dft) 17:36:05 INFO - PID 18870 | RSS: Main: 211910656 17:36:05 INFO - PID 18870 | 17:36:08 INFO - PID 18870 | Cycle 1(1): loaded http://localhost:52339/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52339/tests/kraken/driver.html?testName=audio-fft) 17:36:09 INFO - PID 18870 | RSS: Main: 206856192 17:36:09 INFO - PID 18870 | 17:36:11 INFO - PID 18870 | Cycle 1(1): loaded http://localhost:52339/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52339/tests/kraken/driver.html?testName=audio-oscillator) 17:36:11 INFO - PID 18870 | RSS: Main: 209113088 17:36:11 INFO - PID 18870 | 17:36:14 INFO - PID 18870 | Cycle 1(1): loaded http://localhost:52339/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52339/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:36:14 INFO - PID 18870 | RSS: Main: 209358848 17:36:14 INFO - PID 18870 | 17:36:17 INFO - PID 18870 | Cycle 1(1): loaded http://localhost:52339/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52339/tests/kraken/driver.html?testName=imaging-darkroom) 17:36:18 INFO - PID 18870 | RSS: Main: 211980288 17:36:18 INFO - PID 18870 | 17:36:22 INFO - PID 18870 | Cycle 1(1): loaded http://localhost:52339/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52339/tests/kraken/driver.html?testName=imaging-desaturate) 17:36:22 INFO - PID 18870 | RSS: Main: 209559552 17:36:22 INFO - PID 18870 | 17:36:25 INFO - PID 18870 | Cycle 1(1): loaded http://localhost:52339/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52339/tests/kraken/driver.html?testName=json-parse-financial) 17:36:26 INFO - PID 18870 | RSS: Main: 212230144 17:36:26 INFO - PID 18870 | 17:36:27 INFO - PID 18870 | Cycle 1(1): loaded http://localhost:52339/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52339/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:36:28 INFO - PID 18870 | RSS: Main: 212164608 17:36:28 INFO - PID 18870 | 17:36:30 INFO - PID 18870 | Cycle 1(1): loaded http://localhost:52339/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52339/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:36:30 INFO - PID 18870 | RSS: Main: 211488768 17:36:30 INFO - PID 18870 | 17:36:32 INFO - PID 18870 | Cycle 1(1): loaded http://localhost:52339/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52339/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:36:33 INFO - PID 18870 | RSS: Main: 211988480 17:36:33 INFO - PID 18870 | 17:36:36 INFO - PID 18870 | Cycle 1(1): loaded http://localhost:52339/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52339/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:36:37 INFO - PID 18870 | RSS: Main: 212054016 17:36:37 INFO - PID 18870 | 17:36:40 INFO - PID 18870 | Cycle 1(1): loaded http://localhost:52339/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52339/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:36:40 INFO - PID 18870 | RSS: Main: 212508672 17:36:40 INFO - PID 18870 | 17:36:42 INFO - PID 18870 | Cycle 1(1): loaded http://localhost:52339/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52339/tests/kraken/driver.html?testName=ai-astar) 17:36:42 INFO - PID 18870 | RSS: Main: 214224896 17:36:42 INFO - PID 18870 | 17:36:42 INFO - PID 18870 | __start_tp_report 17:36:42 INFO - PID 18870 | _x_x_mozilla_page_load 17:36:42 INFO - PID 18870 | _x_x_mozilla_page_load_details 17:36:42 INFO - PID 18870 | |i|pagename|runs| 17:36:42 INFO - PID 18870 | |0;ai-astar;99;108;108;111;109;120;114;119;108;118 17:36:42 INFO - PID 18870 | |1;audio-beat-detection;147;119;153;168;116;155;143;118;180;117 17:36:42 INFO - PID 18870 | |2;audio-dft;174;163;169;167;165;167;171;164;178;161 17:36:42 INFO - PID 18870 | |3;audio-fft;75;77;82;159;83;74;74;85;108;80 17:36:42 INFO - PID 18870 | |4;audio-oscillator;87;81;134;86;81;81;122;81;79;131 17:36:42 INFO - PID 18870 | |5;imaging-gaussian-blur;114;119;117;115;114;120;115;118;113;120 17:36:42 INFO - PID 18870 | |6;imaging-darkroom;149;150;153;148;148;157;152;148;152;150 17:36:42 INFO - PID 18870 | |7;imaging-desaturate;96;94;100;102;101;107;99;94;94;94 17:36:42 INFO - PID 18870 | |8;json-parse-financial;53;51;65;56;52;51;56;59;54;52 17:36:42 INFO - PID 18870 | |9;json-stringify-tinderbox;42;39;39;38;38;39;39;39;45;49 17:36:42 INFO - PID 18870 | |10;stanford-crypto-aes;79;77;76;75;81;83;78;74;75;74 17:36:42 INFO - PID 18870 | |11;stanford-crypto-ccm;141;124;119;123;132;131;121;120;123;156 17:36:42 INFO - PID 18870 | |12;stanford-crypto-pbkdf2;172;179;173;168;169;172;175;172;170;172 17:36:42 INFO - PID 18870 | |13;stanford-crypto-sha256-iterative;66;65;66;69;63;71;66;63;65;63 17:36:42 INFO - PID 18870 | __end_tp_report 17:36:42 INFO - PID 18870 | __start_cc_report 17:36:42 INFO - PID 18870 | _x_x_mozilla_cycle_collect,933 17:36:42 INFO - PID 18870 | __end_cc_report 17:36:42 INFO - PID 18870 | __startTimestamp1504312602695__endTimestamp 17:36:42 INFO - PID 18870 | 17:36:42 INFO - PID 18870 | ------- Summary: start ------- 17:36:42 INFO - PID 18870 | Number of tests: 14 17:36:42 INFO - PID 18870 | 17:36:42 INFO - PID 18870 | [#0] ai-astar Cycles:10 Average:111.40 Median:116.00 stddev:6.47 (5.6%) stddev-sans-first:5.07 17:36:42 INFO - PID 18870 | Values: 99.0 108.0 108.0 111.0 109.0 120.0 114.0 119.0 108.0 118.0 17:36:42 INFO - PID 18870 | 17:36:42 INFO - PID 18870 | [#1] audio-beat-detection Cycles:10 Average:141.60 Median:150.00 stddev:23.19 (15.5%) stddev-sans-first:24.52 17:36:42 INFO - PID 18870 | Values: 147.0 119.0 153.0 168.0 116.0 155.0 143.0 118.0 180.0 117.0 17:36:42 INFO - PID 18870 | 17:36:42 INFO - PID 18870 | [#2] audio-dft Cycles:10 Average:167.90 Median:168.00 stddev:5.24 (3.1%) stddev-sans-first:5.07 17:36:42 INFO - PID 18870 | Values: 174.0 163.0 169.0 167.0 165.0 167.0 171.0 164.0 178.0 161.0 17:36:42 INFO - PID 18870 | 17:36:42 INFO - PID 18870 | [#3] audio-fft Cycles:10 Average:89.70 Median:78.50 stddev:26.31 (33.5%) stddev-sans-first:27.36 17:36:42 INFO - PID 18870 | Values: 75.0 77.0 82.0 159.0 83.0 74.0 74.0 85.0 108.0 80.0 17:36:42 INFO - PID 18870 | 17:36:42 INFO - PID 18870 | [#4] audio-oscillator Cycles:10 Average:96.30 Median:81.00 stddev:22.89 (28.3%) stddev-sans-first:24.03 17:36:42 INFO - PID 18870 | Values: 87.0 81.0 134.0 86.0 81.0 81.0 122.0 81.0 79.0 131.0 17:36:42 INFO - PID 18870 | 17:36:42 INFO - PID 18870 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.50 stddev:2.64 (2.2%) stddev-sans-first:2.64 17:36:42 INFO - PID 18870 | Values: 114.0 119.0 117.0 115.0 114.0 120.0 115.0 118.0 113.0 120.0 17:36:42 INFO - PID 18870 | 17:36:42 INFO - PID 18870 | [#6] imaging-darkroom Cycles:10 Average:150.70 Median:151.00 stddev:2.87 (1.9%) stddev-sans-first:2.98 17:36:42 INFO - PID 18870 | Values: 149.0 150.0 153.0 148.0 148.0 157.0 152.0 148.0 152.0 150.0 17:36:42 INFO - PID 18870 | 17:36:42 INFO - PID 18870 | [#7] imaging-desaturate Cycles:10 Average:98.10 Median:94.00 stddev:4.46 (4.7%) stddev-sans-first:4.66 17:36:42 INFO - PID 18870 | Values: 96.0 94.0 100.0 102.0 101.0 107.0 99.0 94.0 94.0 94.0 17:36:42 INFO - PID 18870 | 17:36:42 INFO - PID 18870 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.00 stddev:4.38 (8.0%) stddev-sans-first:4.59 17:36:42 INFO - PID 18870 | Values: 53.0 51.0 65.0 56.0 52.0 51.0 56.0 59.0 54.0 52.0 17:36:42 INFO - PID 18870 | 17:36:42 INFO - PID 18870 | [#9] json-stringify-tinderbox Cycles:10 Average:40.70 Median:39.00 stddev:3.62 (9.3%) stddev-sans-first:3.81 17:36:42 INFO - PID 18870 | Values: 42.0 39.0 39.0 38.0 38.0 39.0 39.0 39.0 45.0 49.0 17:36:42 INFO - PID 18870 | 17:36:42 INFO - PID 18870 | [#10] stanford-crypto-aes Cycles:10 Average:77.20 Median:77.50 stddev:3.05 (3.9%) stddev-sans-first:3.16 17:36:42 INFO - PID 18870 | Values: 79.0 77.0 76.0 75.0 81.0 83.0 78.0 74.0 75.0 74.0 17:36:42 INFO - PID 18870 | 17:36:42 INFO - PID 18870 | [#11] stanford-crypto-ccm Cycles:10 Average:129.00 Median:127.50 stddev:11.68 (9.2%) stddev-sans-first:11.55 17:36:42 INFO - PID 18870 | Values: 141.0 124.0 119.0 123.0 132.0 131.0 121.0 120.0 123.0 156.0 17:36:42 INFO - PID 18870 | 17:36:42 INFO - PID 18870 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.20 Median:172.00 stddev:3.12 (1.8%) stddev-sans-first:3.31 17:36:42 INFO - PID 18870 | Values: 172.0 179.0 173.0 168.0 169.0 172.0 175.0 172.0 170.0 172.0 17:36:42 INFO - PID 18870 | 17:36:42 INFO - PID 18870 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:2.63 (4.0%) stddev-sans-first:2.78 17:36:42 INFO - PID 18870 | Values: 66.0 65.0 66.0 69.0 63.0 71.0 66.0 63.0 65.0 63.0 17:36:42 INFO - PID 18870 | -------- Summary: end -------- 17:36:42 INFO - PID 18870 | 17:36:43 INFO - PID 18870 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:36:43 INFO - TEST-INFO | 18870: exit 0 17:36:43 INFO - TEST-OK | kraken | took 63294ms 17:36:43 INFO - SUITE-END | took 1179s 17:36:43 INFO - Completed test suite (00:19:39) 17:36:43 INFO - javascript benchmark 17:36:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8479.839075139931, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3340.599128104625, 3361.2941282330708, 3377.4504925863266, 3396.8471418786166, 3400.1669590950205, 6436.259830119538, 6471.0, 6522.054302126189, 6523.575967562111, 6528.575642583235, 6286.685665716719, 6377.362263773632, 6389.0416437534395, 6398.936010639893, 6413.13422687937, 5814.069748840187, 5830.621009634381, 5833.125031245326, 5859.53123750099, 5903.321118071433, 5876.294844618652, 5890.764369425217, 5927.37762534932, 5930.347661757203, 5939.316978547479, 5852.180694702745, 5894.941050589504, 5903.4686878180955, 5904.2029326040865, 5947.821565353046, 5855.648661080349, 5876.29484461863, 5905.350411454737, 5916.230889984317, 5918.674472903988, 5806.477417032487, 5854.824355269348, 5881.500072493843, 5960.374160713106, 5980.1328807323125, 6184.196054512884, 6371.7769878054505, 6391.616503009788, 6537.771178008795, 6538.803835884931, 6852.6916288767125, 6914.688839002257, 6926.168859736814, 6927.445804335641, 6967.860642787166, 6976.127984002, 6990.091288132561, 7022.789316320519, 7028.578285302899, 7030.367266945948, 6404.103425520431, 6409.8077057687415, 6420.133282006916, 6488.124103246048, 6502.057201705727, 6870.381665650116, 7016.92983070173, 7048.224695283515, 7074.151101867808, 7087.0786797717, 6602.372774586503, 6609.074729537869, 6611.041398997215, 6613.4709223262025, 6616.007598860209, 6636.30318816527, 6712.630805305755, 6862.897056544156, 6872.587644741231, 6972.616506092214, 6450.608272506082, 6886.621235831977, 6896.517243792886, 6984.61584612841, 7053.259407762213, 6916.1700595927805, 6935.445164386837, 7036.296370363025, 7049.541779784297, 7049.647517624098, 5296.443873393316, 5321.627486075938, 5325.201219817059, 5343.412224655285, 5465.781368745285, 4724.196886529298, 4732.810687572459, 4744.0, 4745.667803253808, 4753.358296629911, 5919.526437884959, 5921.0, 5946.643201407929, 5966.791162309299, 5972.313183983811, 6869.0383346331555, 6878.58728476283, 6892.276310987374, 6913.1358580177475, 7005.964970175117, 5902.940970590325, 5970.970145149333, 6038.849028774228, 6073.423024812636, 6116.571839971249, 5747.339056008613, 5767.509761670221, 5770.2498675171955, 5782.074868021096, 5788.652680839079, 6588.275289718127, 6606.504512161511, 6619.0, 6645.501587380869, 6652.434543063894, 6723.5293529452465, 6782.762603308861, 6810.353016463428, 6836.794896153124, 6876.106106206161, 6609.537332386687, 6700.564463309869, 6734.326567343226, 6739.93260067393, 6814.0, 6824.522283440111, 6855.5543889647015, 6884.51808373409, 6953.860922781617, 6972.1981972071835, 6735.494837887276, 6853.451723862178, 6859.862802743817, 6864.965675171592, 7090.539114957512, 6568.770093046721, 6604.5046621502615, 6624.668766561749, 6682.599044057372, 6742.898856517058, 6736.932630673827, 6781.11845460087, 6781.864362712619, 6782.457403407814, 6804.455643548603, 6683.298253683488, 6704.061431399706, 6725.630090345078, 6805.149356330458, 6811.659417029228, 6762.357576030269, 6786.423154031962, 6792.0, 6835.58986460794, 6871.072405225231, 6283.7800676976085, 6418.069379939976, 6443.581167224115, 6450.161479007699, 6468.935310646833], "value": 6273.28850873587, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [817.9795505112371, 828.9709860154888, 861.8362511122887, 873.4409977614328, 888.5157589113933, 41946.0, 42513.0, 42686.0, 42719.0, 42830.14339713212, 38527.614723852756, 38748.22503549927, 38803.41794873079, 39053.609463905355, 39108.80445597775, 6970.128733908262, 7071.398931090837, 7085.751998680047, 7093.290670932917, 7097.8225544361285, 6931.584104953694, 6959.8956015659805, 7008.264132266115, 7022.332878376572, 7026.191987921404, 6951.89572156418, 6989.4408447324095, 7031.332023457763, 7285.672144753499, 7975.840483190333, 7849.175836537139, 7891.4081443891655, 7911.090224624127, 7920.089189743196, 7974.202579742037, 7701.807454813618, 7749.341305988998, 7770.57261850598, 7792.961035194844, 7795.415343849234, 38846.0, 39282.60717392818, 39337.40993885065, 39525.40711889324, 39706.20587588232, 3618.0412190769466, 3627.818609069536, 3633.7819730816236, 3650.7809531428197, 3667.1382225177063, 31953.201169970704, 32118.197045073826, 32197.03408897737, 32285.35429291401, 32563.02310930676, 5125.8718532037, 5196.974015129938, 5224.059669259532, 5241.947580524184, 5287.41838397776, 183820.0, 185266.0, 185321.0, 190250.0, 201609.0, 48051.0, 48522.0, 48531.514684853406, 48712.51287487151, 48938.0, 50328.24507632388, 50846.49153508492, 50983.980320393384, 51317.23024154641, 51436.485635143916, 14645.48740794067, 14912.105273683614, 14955.626109347353, 14983.101013939198, 15204.46784362542, 12358.073144514197, 12374.75250494985, 12401.6279511613, 12421.75156496865, 12428.0, 13758.174509529461, 13771.931140344435, 13787.172769633855, 13824.308784560933, 13964.092333998258, 5615.157726341082, 5617.466340697627, 5724.542036637059, 5756.222909907109, 5803.187553742479, 1010.3382284603597, 1013.6705570689555, 1021.836506159011, 1034.0693375961694, 1039.1323245090282, 23100.768992310197, 23107.422314442272, 23108.65337019946, 23201.88399057994, 23601.645975310385, 1107.684309971654, 1131.9207655464204, 1133.5295852221236, 1138.3795831271977, 1141.6175581180398, 11966.700832479084, 12192.39038048094, 12268.141230113806, 12288.508459661693, 12291.016718662486, 224229.0, 225140.0, 226059.0, 226250.0, 226493.0, 52626.0, 52912.20631690528, 53059.0, 53216.46783532193, 53557.0, 1427.093795439903, 1450.0719539494733, 1461.1598330959528, 1461.1598330959741, 1480.000999325451, 22258.443538911655, 22893.08427662875, 22945.196918107787, 23227.3031809046, 23504.177353792536, 38141.0, 38146.809265953496, 38223.23553528969, 38231.04422389463, 38250.80874595609, 191472.0, 191957.0, 193256.0, 193429.0, 193942.0, 30731.0, 33248.501272481415, 33716.83141584276, 34636.65363346334, 35231.82384088063, 44144.11711765811, 44301.0, 44471.0, 44643.33035004543, 45444.318335224336, 31753.206169845016, 31918.0, 32154.51768223525, 32411.675883240867, 32544.83727581347, 32783.83608081944, 33261.00216993494, 33955.83022084874, 34476.13809654678, 34547.48178777371, 37467.43798843074, 38408.231835362574, 38627.420588691755, 38768.0, 38776.2244755109, 32504.34991300208, 32957.34085318328, 33372.0, 33416.498752518244, 33574.32851343008, 23447.531049378576, 23692.0, 24022.27933162008, 24329.51340973206, 24468.755312446647], "value": 17103.349225832462, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.5073761196563, 570.8601392658796, 571.8456016875441, 598.5540772124766, 599.6042611876161, 21616.783832161695, 22339.553208935813, 22364.552708945812, 22381.10475580975, 22429.43926401841, 23259.76740232597, 25287.2413827585, 25429.618555721678, 25497.0, 25559.744402555967, 6688.93311066888, 6757.222919364287, 6795.728170873159, 6860.34826691466, 6866.485013623973, 6145.385461453863, 6674.833129171785, 6778.49161312901, 6783.389494945454, 6799.898001529957, 6679.766208182715, 6700.095487109251, 6702.463802895757, 6702.7654032108885, 6710.83222919426, 6633.436157926582, 6659.433948114416, 6694.93305066948, 6698.56459330142, 6705.2624211336715, 6191.814245572595, 6197.566170368076, 6200.844978875519, 6204.6587437690905, 6221.440070393686, 28608.856955715295, 28895.42209155805, 29542.261443464085, 29557.0, 30390.54414183789, 1972.4378552112723, 2011.7585889693185, 2017.4552870724822, 2022.0395312226722, 2026.493376655836, 19195.616087678307, 19539.90230048855, 19616.80383196164, 19642.0, 19661.0, 2392.7726865947707, 2416.492536567323, 2493.47636996231, 2495.4011037350906, 2508.8871000804825, 132208.0, 137958.62041379558, 138008.0, 138505.0, 138810.3059484696, 14107.576772696835, 14585.635359116108, 14676.3395647194, 14749.0, 14901.403943842342, 23044.308670739905, 23190.0, 23190.42023949381, 23203.303900883002, 23215.187468438522, 8948.060453652402, 9308.488033158106, 9357.298202634829, 9406.153446189875, 9483.383580067273, 8340.916590834135, 8364.0, 8487.405881588353, 8500.10748871372, 8688.218060374596, 8889.48865198256, 8899.15458031486, 9016.549172541347, 9055.637774489078, 9351.251899847995, 2683.87328761994, 2702.8243164194264, 2776.1255204610484, 2813.9015134470196, 2852.6434195725533, 937.4000639590668, 970.4371464550544, 995.3032876986139, 998.2513115163628, 1002.2883752535636, 19851.30520431778, 19915.943736210953, 20063.39809805708, 20233.291834785712, 20480.078396472196, 1023.7696518283476, 1037.4605205293205, 1039.1998161415818, 1050.553514756241, 1051.332403923514, 11080.556777728962, 11202.0, 11229.382383968798, 11300.434978251054, 11301.717457063474, 436880.0, 444200.0, 445163.0, 452644.0, 453014.2047869285, 112327.0, 115776.0, 116476.0, 116491.0, 118547.0, 1131.3664347965166, 1157.6584907452323, 1170.7307319316435, 1176.147293212414, 1180.2682336951145, 17900.462986110437, 17964.0, 18100.18549165317, 18381.17284722164, 18462.35381761632, 53347.19979200237, 54752.262386881295, 54982.72508637431, 55108.897822042534, 55534.0, 67001.66499167473, 67385.66307168432, 67750.3224967744, 68213.65893170502, 68669.31330686629, 12182.269063856196, 14410.42358305656, 14657.120572765669, 14752.262386880398, 14812.555623331318, 17107.91446042762, 18401.171947262672, 18794.15426305792, 18984.43046708601, 23991.0, 18482.907585461988, 18720.344787932358, 18764.249430022646, 18966.905165474083, 19016.334428295497, 16193.433229836899, 16212.594685132966, 17408.47774566765, 17568.2094305754, 18052.72920906161, 17780.91109544444, 18808.905955470134, 18860.434186974413, 18870.622587548445, 19076.809231907504, 14045.367958442099, 14506.1296322221, 14546.709065818413, 14679.19264440466, 14756.778648320069, 17172.39896603613, 17485.737713934046, 17496.91251543734, 18500.444986650422, 18502.907485462485], "value": 11828.533974014475, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1340.162398500937, 1360.476216656587, 1384.114166933163, 1392.5752645443135, 1397.4829313154135, 14819.851801481982, 15398.769018464718, 15442.382304707813, 15511.0, 15525.068495890255, 14862.925685371558, 15485.83856210783, 15735.84264157361, 15768.921155394208, 15792.289346979385, 2627.684677838662, 2724.50958827411, 2724.8637568121517, 2727.2227415186676, 2728.7134850840675, 2623.855687937172, 2672.3987102901788, 2674.4651069786023, 2676.1302576662565, 2676.397810492633, 2528.469021505486, 2753.4768394005173, 2753.8347699138017, 2757.7931655125844, 2759.751622353988, 2381.2618088392655, 2408.205292253552, 2469.6789417375717, 2497.988314732527, 2526.393665520271, 2590.2617753940035, 2646.2458199413077, 2651.6287719719253, 2652.8010399220134, 2658.8005899557415, 14812.851871481254, 14973.326200321011, 15119.39522419102, 15140.167290799001, 15148.469803556933, 7440.404767618577, 7694.269044440769, 7700.306972372513, 7700.730474433424, 7721.806954826175, 4829.299751536043, 4902.28916807061, 4916.0, 5036.043151801183, 5055.191169412913, 1893.697008478637, 1914.2917120665443, 1924.3457224543722, 1960.0, 1982.5935683184912, 26102.477950441076, 26235.081772138074, 26269.868650656623, 26379.73620263792, 26395.284306520014, 6489.67551622417, 6632.6352050636715, 6669.766558170538, 6750.797476075725, 6874.209465911485, 17250.309987600605, 17324.740128898076, 17423.91288043577, 17452.301907923542, 17475.47573572795, 7724.0731112266885, 7876.133625301212, 7922.366210703245, 7991.72028978983, 7992.720254791171, 7001.964990175016, 7029.9648501757165, 7078.787636370917, 7092.5389849659605, 7140.7857764267155, 4835.371401717754, 5002.624803139779, 5035.748212589356, 5086.847394578094, 5110.872228194325, 1376.2636989210723, 1381.1505923856957, 1415.5399495164113, 1427.293489722594, 1443.6896067345572, 900.4732231644562, 901.9143181397887, 916.6562539047857, 934.9298802589833, 936.7892224249489, 8252.958735206286, 8280.171982801672, 8286.171382861665, 8320.209580089882, 8427.452215605967, 715.5670819154441, 716.7813816785826, 718.2350796401815, 720.3121019426435, 782.7808213700173, 5336.626436149432, 5344.118220493653, 5594.300712410949, 5655.519280861173, 5708.086706127, 20774.85738284409, 21392.572148557254, 21475.78524214738, 21719.348419547438, 21992.78007219939, 20845.58308833815, 21037.789622103584, 21927.890360548095, 21934.0, 21984.340469785933, 10054.396736195851, 10270.664813574187, 10600.522976466078, 10620.681379558471, 10635.893641063645, 8590.484570925515, 8614.78463038429, 8634.30925525969, 8730.869036964577, 8814.779630509289, 18498.16758245855, 18602.34891778781, 18906.14922328526, 19113.90443047776, 19159.233630654617, 18787.24851005999, 18881.90559047196, 19458.8054119457, 19583.314583989493, 19928.50178745543, 11037.779244415227, 11251.606193783506, 11260.831087533857, 11285.830712539482, 12065.215760975509, 13995.650108747363, 14265.144091354552, 14278.2146981917, 14284.71430571362, 14311.713765724835, 10904.890951090387, 10981.176411768989, 11172.664820055412, 11229.3262404256, 11459.828102578635, 10472.842907356244, 10804.135669146606, 10827.621033263798, 11015.0, 11118.166137539554, 10252.179825613784, 10319.432431216055, 10327.225458090823, 10395.480225988822, 10461.633842815465, 6845.760398386033, 6892.655367231719, 6933.1333583302085, 6947.131608548932, 7151.106111736033, 7338.853222935618, 7349.338559529668, 7408.814779630552, 7427.517211381243, 7474.177840437439], "value": 6570.621242033235, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.36827281761384, 508.32646743065436, 531.619891777379, 534.3774502704349, 535.3484500316521, 6333.493320534358, 6348.36516348365, 6382.212980365493, 6412.967935160318, 6438.871222575557, 6241.687915604224, 6254.812355629327, 6356.237251529811, 6359.138387725518, 6365.681715914197, 5189.299444574972, 5251.842444726664, 5254.343207099113, 5276.445973172819, 5285.2600635911, 5222.8694282642855, 5248.134057880445, 5269.314989051418, 5288.338957630296, 5314.681119132864, 4921.63087768417, 4973.825916092937, 5048.29323894655, 5194.805194805197, 5205.843824685247, 5001.674891132083, 5003.349564556621, 5043.2435134730085, 5050.772715227824, 5088.109580823341, 4429.579189976947, 4459.531749166355, 4465.330200469923, 4466.240739074366, 4486.7083639563325, 6098.451139397431, 6177.7837775677635, 6199.380061993809, 6270.623762574261, 6277.529185311074, 5698.743556539925, 5730.882477916808, 5733.397993210736, 5736.770529178828, 5782.797602083907, 6699.933000670028, 6740.191177058784, 6747.291534388867, 6758.695858686371, 6790.32096790322, 4864.9053962858425, 4872.512748725135, 4938.9753051234875, 4945.67853089548, 4951.257311403318, 6774.796756097325, 6913.274106218875, 6928.930710692828, 7002.649867506655, 7151.284871512859, 4851.902961940741, 4879.194932836113, 4907.656464047483, 4948.752562371867, 4950.727709975914, 5052.898942021139, 5056.544910958031, 5069.6451248413, 5074.873128171751, 5091.898162036812, 5655.858603534944, 5684.175794509773, 5712.6286791358425, 5724.370319264877, 5738.8278351649515, 6548.967255163694, 6673.698628767441, 6684.231313398924, 6713.731450741916, 6721.395074443323, 1755.5347832824057, 1758.384565402099, 1765.7792775903013, 1768.9823101768818, 1779.7063484525167, 1009.2733232072895, 1195.1932445599186, 1214.6052532926833, 1223.0, 1245.9314737689333, 5827.796027139115, 5829.446202610745, 5845.357010728816, 5893.14549390336, 5917.55618328627, 6729.629870357177, 6869.965650171717, 6876.040328506352, 6892.896606550906, 6960.512764106464, 5821.214136091573, 5867.70661466925, 5916.615419997686, 5919.668074683162, 5932.110183472513, 3646.124930016777, 3655.3420384330543, 3713.1830997180577, 3716.2939041582554, 3727.366347720894, 6526.902096468557, 6557.934420655828, 6584.76953306632, 6656.30108838565, 6673.995602638378, 6986.580805151605, 7001.0, 7007.684654190573, 7048.92951070493, 7099.503034787515, 6583.934160658428, 6623.43700785429, 6663.567797753882, 6666.633335166613, 6755.3582409671, 6757.0, 6869.450443964571, 6888.483363747639, 6891.689873955584, 6921.48088893325, 6135.539834512519, 6742.730290788313, 6786.21958474762, 6812.863742725019, 6889.138857642794, 6539.836504087436, 6571.737130514726, 6587.440067594308, 6642.368974947372, 6715.26132125456, 5018.698878067327, 5020.297158397901, 5025.698458092526, 5042.722650254272, 5065.620078494054, 6646.534742567974, 6932.301586111435, 6947.3747362737595, 6974.302569743188, 6980.720771169096, 6312.337204593451, 6573.96713016451, 6633.867322653617, 6651.572176155765, 6709.429698475684, 6589.4069533742195, 6630.30381809903, 6634.203895532374, 6643.46852251809, 6662.367075127855, 6388.169537960035, 6423.96788016057, 6532.8366790830605, 6567.835804104936, 6638.5684930479365, 6530.453123281829, 6643.269240383457, 6671.8665626686225, 6683.43190828785, 6704.229013663491, 6366.713497892699, 6369.254905095733, 6498.447631951337, 6504.15445992018, 6521.673916304071], "value": 5132.086405673983, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [853.8804567360567, 855.6705668317703, 877.6533269358604, 918.3433844800968, 929.6932012435897, 17347.47957561272, 17897.10514474276, 17904.731429028576, 17957.820421795794, 19781.0, 18652.720209196872, 18786.624267514642, 18822.811771882276, 18907.24371025161, 18989.90505047473, 717.720089165226, 739.5119221313934, 967.743547959793, 977.237754551451, 983.6360546597768, 784.8979632647777, 971.6696323250094, 996.217968894418, 996.7358649957768, 997.7605374710054, 1048.821700310945, 1051.4637534857202, 1052.3896140238644, 1053.9420331881743, 1058.0847566854654, 1033.6640591807654, 1045.6549338718205, 1047.314009323894, 1047.7171163785736, 1050.8266136087575, 766.1380946435261, 767.106321135876, 769.5190505933791, 770.4760762681375, 771.2865599320662, 17751.556211094696, 17945.641087178316, 18020.63958720818, 18120.818791812046, 19551.90224048885, 20170.294039708686, 20878.5824283515, 21071.68392474114, 21392.89303553488, 21586.89206553957, 15652.843471565366, 15806.288717007763, 16239.51281461558, 16408.83591164085, 16456.259468323955, 3660.890173294805, 3663.139162296858, 3684.078980254936, 3716.535433070866, 3717.739758216926, 118951.0, 127272.0, 127674.0, 127929.0, 128892.3555382217, 16015.439459618858, 16376.754348684779, 16404.507864764077, 16498.257578409, 16638.3344666212, 30067.849660751555, 30282.0, 30320.393592128035, 30372.544411833842, 30375.0, 8083.595820208966, 8112.067112282163, 8146.551939643258, 8150.470219435718, 8162.959185204155, 3601.4777857210556, 3763.6236376362144, 4004.0990777074926, 4083.959160408358, 4185.037441388497, 5032.421271553745, 5116.155834287301, 5138.588912887035, 5156.432792392834, 5171.146760784503, 964.9758756031156, 982.3221976836103, 991.7570195302197, 992.4045572656348, 994.8408254679216, 681.6830173969029, 687.3161204601354, 702.8207807009283, 708.5111273221461, 713.6717110129399, 11349.432528373549, 11661.6501504955, 12173.817392739116, 12805.487780488862, 12960.092793504553, 690.2545251128769, 694.9200841903145, 697.8185671725388, 710.3216428311054, 712.6579241963783, 5220.1647736362, 5591.161325801081, 5672.347680016768, 5711.286089238845, 5923.318818335946, 106120.0, 110237.0, 110488.0, 112019.43990279996, 113173.43413283046, 96379.51810241044, 97249.28395407274, 98861.0, 99798.0, 99974.50012749889, 80093.19906800974, 83928.16071839203, 84701.57649211715, 84815.72776408358, 86611.1338886603, 10129.290949633454, 10161.034701703325, 10221.33561318512, 10297.485125743833, 10383.48082595852, 40688.0, 41039.794801027, 41469.0, 42087.158256835304, 42431.575684242765, 52406.21390679067, 52572.73713631407, 53444.73277633587, 53819.46180538145, 54097.45902540924, 15159.317830697866, 15817.446389376317, 15965.28156232949, 16018.119003454922, 16082.276297566403, 17297.308107676054, 17346.04596747191, 17392.47822565325, 17494.650106998044, 17673.82326176722, 15746.527604171893, 15876.682466350378, 15920.124393158025, 16092.436764713178, 16164.919175404048, 14923.104613723212, 14980.700385992, 14997.400103995718, 15207.011544249588, 15237.0, 14331.856681433052, 14656.853431465548, 16372.181390930644, 16730.916345418194, 16912.0, 11590.478428470908, 12161.33112678811, 12274.07944404191, 12395.876041239833, 13569.932150339186, 13458.730825383242, 13827.446902123802, 13956.0, 14515.491957781427, 14540.85459145395], "value": 8687.4670727077, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1511.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 108.0, 108.0, 111.0, 109.0, 120.0, 114.0, 119.0, 108.0, 118.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 119.0, 153.0, 168.0, 116.0, 155.0, 143.0, 118.0, 180.0, 117.0], "value": 141.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 163.0, 169.0, 167.0, 165.0, 167.0, 171.0, 164.0, 178.0, 161.0], "value": 167.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 82.0, 159.0, 83.0, 74.0, 74.0, 85.0, 108.0, 80.0], "value": 89.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 81.0, 134.0, 86.0, 81.0, 81.0, 122.0, 81.0, 79.0, 131.0], "value": 96.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 119.0, 117.0, 115.0, 114.0, 120.0, 115.0, 118.0, 113.0, 120.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 153.0, 148.0, 148.0, 157.0, 152.0, 148.0, 152.0, 150.0], "value": 150.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 100.0, 102.0, 101.0, 107.0, 99.0, 94.0, 94.0, 94.0], "value": 98.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 65.0, 56.0, 52.0, 51.0, 56.0, 59.0, 54.0, 52.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [42.0, 39.0, 39.0, 38.0, 38.0, 39.0, 39.0, 39.0, 45.0, 49.0], "value": 40.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 77.0, 76.0, 75.0, 81.0, 83.0, 78.0, 74.0, 75.0, 74.0], "value": 77.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 124.0, 119.0, 123.0, 132.0, 131.0, 121.0, 120.0, 123.0, 156.0], "value": 129.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 179.0, 173.0, 168.0, 169.0, 172.0, 175.0, 172.0, 170.0, 172.0], "value": 172.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 69.0, 63.0, 71.0, 66.0, 63.0, 65.0, 63.0], "value": 65.7, "unit": "score"}]}]} 17:36:43 INFO - Return code: 0 17:36:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:36:43 INFO - # TBPL SUCCESS # 17:36:43 INFO - Running post-action listener: _package_coverage_data 17:36:43 INFO - Running post-action listener: _resource_record_post_action 17:36:43 INFO - [mozharness: 2017-09-02 00:36:43.975308Z] Finished run-tests step (success) 17:36:43 INFO - Running post-run listener: _resource_record_post_run 17:36:44 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 5816320; Write bytes: 299827200; Read time: 5988; Write time: 251828 17:36:44 INFO - TinderboxPrint: CPU usage
13.3% 17:36:44 INFO - TinderboxPrint: I/O read bytes / time
5,816,320 / 5,988 17:36:44 INFO - TinderboxPrint: I/O write bytes / time
299,827,200 / 251,828 17:36:44 INFO - TinderboxPrint: CPU idle
8,265.5 (86.7%) 17:36:44 INFO - TinderboxPrint: CPU user
1,202.2 (12.6%) 17:36:44 INFO - TinderboxPrint: Swap in / out
0 / 0 17:36:44 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 36864; Write bytes: 12275712; Read time: 44; Write time: 3584 17:36:44 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:36:44 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 5779456; Write bytes: 281616384; Read time: 5944; Write time: 233932 17:36:44 INFO - Running post-run listener: _upload_blobber_files 17:36:44 INFO - Blob upload gear active. 17:36:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:36:44 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 17:36:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:36:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:36:45 INFO - (blobuploader) - INFO - Open directory for files ... 17:36:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:36:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:36:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:46 INFO - (blobuploader) - INFO - Done attempting. 17:36:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:36:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:36:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:49 INFO - (blobuploader) - INFO - Done attempting. 17:36:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:36:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:36:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:50 INFO - (blobuploader) - INFO - Done attempting. 17:36:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:36:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:36:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:51 INFO - (blobuploader) - INFO - Done attempting. 17:36:51 INFO - (blobuploader) - INFO - Iteration through files over. 17:36:51 INFO - Return code: 0 17:36:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:36:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:36:51 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a9a460b4957b705eea55eedc2cd233efd0b7c17ca3e8aee64e7358d8ce38ef5d9ac6eae1e0aa93121a442b7b33d0330c7f7441137f925c2c3f23cbb5c5661fb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54ed9a0d21a6a8b3a50fda55f2d555e74345ecb4bac905c759e2ad36c1e2b8d4cab3d7bb05efca039c30dde691d978806fcd5137ce3ba0bfee421bba5bc5e56a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0403a3c281c8d960f771a772c913a13a1200517f878c23ad6e2b307f81a24b93ea9e3be7f743074df60cdf7c6ec46f74905570abb408bab84397c4b21a8ac66e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/917e8c6b81db2764b02ef24fbe8fc6be80dd9854cfe5366e6674dd899661a115225fb1447190ac69af9c652aae888da97471b51ecac941eb3cde37aec93c025c"} 17:36:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:36:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:36:51 INFO - Contents: 17:36:51 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a9a460b4957b705eea55eedc2cd233efd0b7c17ca3e8aee64e7358d8ce38ef5d9ac6eae1e0aa93121a442b7b33d0330c7f7441137f925c2c3f23cbb5c5661fb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54ed9a0d21a6a8b3a50fda55f2d555e74345ecb4bac905c759e2ad36c1e2b8d4cab3d7bb05efca039c30dde691d978806fcd5137ce3ba0bfee421bba5bc5e56a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0403a3c281c8d960f771a772c913a13a1200517f878c23ad6e2b307f81a24b93ea9e3be7f743074df60cdf7c6ec46f74905570abb408bab84397c4b21a8ac66e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/917e8c6b81db2764b02ef24fbe8fc6be80dd9854cfe5366e6674dd899661a115225fb1447190ac69af9c652aae888da97471b51ecac941eb3cde37aec93c025c"} 17:36:51 INFO - Running post-run listener: copy_logs_to_upload_dir 17:36:51 INFO - Copying logs to upload dir... 17:36:51 INFO - mkdir: /builds/slave/test/build/upload/logs 17:36:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.723478 ========= master_lag: 0.66 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-09-01 17:36:51.370773) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 17:36:51.378555) ========= 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=5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a9a460b4957b705eea55eedc2cd233efd0b7c17ca3e8aee64e7358d8ce38ef5d9ac6eae1e0aa93121a442b7b33d0330c7f7441137f925c2c3f23cbb5c5661fb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54ed9a0d21a6a8b3a50fda55f2d555e74345ecb4bac905c759e2ad36c1e2b8d4cab3d7bb05efca039c30dde691d978806fcd5137ce3ba0bfee421bba5bc5e56a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0403a3c281c8d960f771a772c913a13a1200517f878c23ad6e2b307f81a24b93ea9e3be7f743074df60cdf7c6ec46f74905570abb408bab84397c4b21a8ac66e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/917e8c6b81db2764b02ef24fbe8fc6be80dd9854cfe5366e6674dd899661a115225fb1447190ac69af9c652aae888da97471b51ecac941eb3cde37aec93c025c"} build_url:https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011309 build_url: 'https://queue.taskcluster.net/v1/task/Dw0q0U3MRZ6Rav7W5HAwLw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a9a460b4957b705eea55eedc2cd233efd0b7c17ca3e8aee64e7358d8ce38ef5d9ac6eae1e0aa93121a442b7b33d0330c7f7441137f925c2c3f23cbb5c5661fb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54ed9a0d21a6a8b3a50fda55f2d555e74345ecb4bac905c759e2ad36c1e2b8d4cab3d7bb05efca039c30dde691d978806fcd5137ce3ba0bfee421bba5bc5e56a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0403a3c281c8d960f771a772c913a13a1200517f878c23ad6e2b307f81a24b93ea9e3be7f743074df60cdf7c6ec46f74905570abb408bab84397c4b21a8ac66e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/917e8c6b81db2764b02ef24fbe8fc6be80dd9854cfe5366e6674dd899661a115225fb1447190ac69af9c652aae888da97471b51ecac941eb3cde37aec93c025c"}' ========= master_lag: 0.55 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 17:36:51.935141) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 17:36:51.935565) ========= 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=5bd038846534b2fdfa68eeae000001e2-1504311339.983619-34111985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004503 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 17:36:51.966382) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 17:36:51.966834) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 17:36:51.971402) ========= ========= Total master_lag: 2.77 =========