builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-041 starttime: 1504864015.13 results: success (0) revision: fd1ab37308c5cf0e1fd2471708f53d8e6ddc69aa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-08 02:46:55.132880) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-08 02:46:55.133366) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-08 02:46:55.133648) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-08 02:46:55.161520) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-08 02:46:55.161801) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-08 02:46:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 47.0M=0s 2017-09-08 02:46:55 (47.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.230761 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-08 02:46:55.413194) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-08 02:46:55.413532) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014358 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-08 02:46:55.468971) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-08 02:46:55.469326) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fd1ab37308c5cf0e1fd2471708f53d8e6ddc69aa --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 fd1ab37308c5cf0e1fd2471708f53d8e6ddc69aa --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=8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-08 02:46:55,476 truncating revision to first 12 chars 2017-09-08 02:46:55,476 Setting DEBUG logging. 2017-09-08 02:46:55,476 attempt 1/10 2017-09-08 02:46:55,476 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fd1ab37308c5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-08 02:46:55,926 unpacking tar archive at: mozilla-central-fd1ab37308c5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.798052 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-08 02:46:56.283610) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-08 02:46:56.286937) ========= script_repo_revision: fd1ab37308c5cf0e1fd2471708f53d8e6ddc69aa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-08 02:46:56.287341) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-08 02:46:56.287604) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-08 02:46:56.300986) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-08 02:46:56.301276) ========= /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=8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:46:56 INFO - MultiFileLogger online at 20170908 02:46:56 in /builds/slave/test 02:46:56 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 02:46:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:46:56 INFO - {'append_to_log': False, 02:46:56 INFO - 'base_work_dir': '/builds/slave/test', 02:46:56 INFO - 'blob_upload_branch': 'Firefox', 02:46:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:46:56 INFO - 'branch': 'Firefox', 02:46:56 INFO - 'buildbot_json_path': 'buildprops.json', 02:46:56 INFO - 'code_coverage': False, 02:46:56 INFO - 'config_files': ('talos/linux_config.py',), 02:46:56 INFO - 'default_actions': ('clobber', 02:46:56 INFO - 'read-buildbot-config', 02:46:56 INFO - 'download-and-extract', 02:46:56 INFO - 'populate-webroot', 02:46:56 INFO - 'create-virtualenv', 02:46:56 INFO - 'install', 02:46:56 INFO - 'setup-mitmproxy', 02:46:56 INFO - 'run-tests'), 02:46:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:46:56 INFO - 'disable_ccov_upload': False, 02:46:56 INFO - 'disable_stylo': False, 02:46:56 INFO - 'download_minidump_stackwalk': True, 02:46:56 INFO - 'download_symbols': 'ondemand', 02:46:56 INFO - 'enable_stylo': False, 02:46:56 INFO - 'enable_webrender': False, 02:46:56 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 02:46:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:46:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:46:56 INFO - 'gecko_profile': False, 02:46:56 INFO - 'gecko_profile_interval': 0, 02:46:56 INFO - 'installer_path': 'installer.exe', 02:46:56 INFO - 'log_level': 'info', 02:46:56 INFO - 'log_name': 'talos', 02:46:56 INFO - 'log_to_console': True, 02:46:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:46:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:46:56 INFO - 'opt_config_files': (), 02:46:56 INFO - 'pip_index': False, 02:46:56 INFO - 'suite': 'dromaeojs-e10s', 02:46:56 INFO - 'system_bits': '32', 02:46:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:46:56 INFO - 'title': 'talos-linux64-ix-041', 02:46:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:46:56 INFO - 'use_talos_json': True, 02:46:56 INFO - 'verify': 'False', 02:46:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:46:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:46:56 INFO - 'work_dir': 'build'} 02:46:56 INFO - [mozharness: 2017-09-08 09:46:56.568246Z] Running clobber step. 02:46:56 INFO - Running pre-action listener: _resource_record_pre_action 02:46:56 INFO - Running main action method: clobber 02:46:56 INFO - rmtree: /builds/slave/test/build 02:46:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:46:57 INFO - Running post-action listener: _resource_record_post_action 02:46:57 INFO - [mozharness: 2017-09-08 09:46:57.544702Z] Finished clobber step (success) 02:46:57 INFO - [mozharness: 2017-09-08 09:46:57.544799Z] Running read-buildbot-config step. 02:46:57 INFO - Running pre-action listener: _resource_record_pre_action 02:46:57 INFO - Running main action method: read_buildbot_config 02:46:57 INFO - Using buildbot properties: 02:46:57 INFO - { 02:46:57 INFO - "project": "", 02:46:57 INFO - "product": "firefox", 02:46:57 INFO - "who": "archaeopteryx@coole-files.de", 02:46:57 INFO - "installer_path": "public/build/target.tar.bz2", 02:46:57 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 02:46:57 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:46:57 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 02:46:57 INFO - "stage_platform": "linux64-stylosequential", 02:46:57 INFO - "basedir": "/builds/slave/test", 02:46:57 INFO - "buildnumber": 192, 02:46:57 INFO - "platform": "ubuntu64_hw_styloseq", 02:46:57 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 02:46:57 INFO - "slavebuilddir": "test", 02:46:57 INFO - "taskId": "fXp8KBB-RaeYzmp9UwM43A", 02:46:57 INFO - "branch": "mozilla-central", 02:46:57 INFO - "script_repo_revision": "production", 02:46:57 INFO - "revision": "fd1ab37308c5cf0e1fd2471708f53d8e6ddc69aa", 02:46:57 INFO - "slavename": "talos-linux64-ix-041", 02:46:57 INFO - "repo_path": "mozilla-central" 02:46:57 INFO - } 02:46:57 INFO - Finding installer, test and symbols from parent task. 02:46:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fXp8KBB-RaeYzmp9UwM43A'}, attempt #1 02:46:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fXp8KBB-RaeYzmp9UwM43A'}, attempt #1 02:46:58 INFO - Task dependencies: F69fQAZfRECOnrdgihUWdw 02:46:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F69fQAZfRECOnrdgihUWdw'}, attempt #1 02:46:58 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/F69fQAZfRECOnrdgihUWdw/artifacts/public/build/target.tar.bz2 02:46:58 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/F69fQAZfRECOnrdgihUWdw/artifacts/public/build/target.test_packages.json 02:46:58 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/F69fQAZfRECOnrdgihUWdw/artifacts/public/build/target.crashreporter-symbols.zip 02:46:58 INFO - Running post-action listener: _resource_record_post_action 02:46:58 INFO - [mozharness: 2017-09-08 09:46:58.916095Z] Finished read-buildbot-config step (success) 02:46:58 INFO - [mozharness: 2017-09-08 09:46:58.916310Z] Running download-and-extract step. 02:46:58 INFO - Running pre-action listener: _resource_record_pre_action 02:46:58 INFO - Running main action method: download_and_extract 02:46:58 INFO - mkdir: /builds/slave/test/build/tests 02:46:58 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')]} 02:46:58 INFO - trying https://queue.taskcluster.net/v1/task/F69fQAZfRECOnrdgihUWdw/artifacts/public/build/target.test_packages.json 02:46:58 INFO - Downloading https://queue.taskcluster.net/v1/task/F69fQAZfRECOnrdgihUWdw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:46:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F69fQAZfRECOnrdgihUWdw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:46:59 INFO - Downloaded 989 bytes. 02:46:59 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:46:59 INFO - Using the following test package requirements: 02:46:59 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:46:59 INFO - u'common': [u'target.common.tests.zip'], 02:46:59 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:46:59 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:46:59 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:46:59 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:46:59 INFO - u'mozbase': [u'target.common.tests.zip'], 02:46:59 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:46:59 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:46:59 INFO - u'web-platform': [u'target.common.tests.zip', 02:46:59 INFO - u'target.web-platform.tests.tar.gz'], 02:46:59 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:46:59 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 02:46:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F69fQAZfRECOnrdgihUWdw/artifacts/public/build/target.common.tests.zip 02:46:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F69fQAZfRECOnrdgihUWdw/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:46:59 INFO - Fetch https://queue.taskcluster.net/v1/task/F69fQAZfRECOnrdgihUWdw/artifacts/public/build/target.common.tests.zip into memory 02:47:00 INFO - Content-Length response header: 55023663 02:47:00 INFO - Bytes received: 55023663 02:47:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F69fQAZfRECOnrdgihUWdw/artifacts/public/build/target.talos.tests.zip 02:47:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F69fQAZfRECOnrdgihUWdw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:47:11 INFO - Fetch https://queue.taskcluster.net/v1/task/F69fQAZfRECOnrdgihUWdw/artifacts/public/build/target.talos.tests.zip into memory 02:47:12 INFO - Content-Length response header: 14496665 02:47:12 INFO - Bytes received: 14496665 02:47:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:47:12 INFO - trying https://queue.taskcluster.net/v1/task/F69fQAZfRECOnrdgihUWdw/artifacts/public/build/target.tar.bz2 02:47:12 INFO - Downloading https://queue.taskcluster.net/v1/task/F69fQAZfRECOnrdgihUWdw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:47:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F69fQAZfRECOnrdgihUWdw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:47:13 INFO - Downloaded 62765897 bytes. 02:47:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F69fQAZfRECOnrdgihUWdw/artifacts/public/build/target.tar.bz2 02:47:13 INFO - mkdir: /builds/slave/test/properties 02:47:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:47:13 INFO - Writing to file /builds/slave/test/properties/build_url 02:47:13 INFO - Contents: 02:47:13 INFO - build_url:https://queue.taskcluster.net/v1/task/F69fQAZfRECOnrdgihUWdw/artifacts/public/build/target.tar.bz2 02:47:13 INFO - Running post-action listener: _resource_record_post_action 02:47:13 INFO - Running post-action listener: find_tests_for_verification 02:47:13 INFO - Running post-action listener: set_extra_try_arguments 02:47:13 INFO - [mozharness: 2017-09-08 09:47:13.646252Z] Finished download-and-extract step (success) 02:47:13 INFO - [mozharness: 2017-09-08 09:47:13.646350Z] Running populate-webroot step. 02:47:13 INFO - Running pre-action listener: _resource_record_pre_action 02:47:13 INFO - Running main action method: populate_webroot 02:47:13 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:47:13 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:47:13 INFO - 'tests': ['tresize', 02:47:13 INFO - 'tcanvasmark']}, 02:47:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:47:13 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:47:13 INFO - 'tests': ['dromaeo_css', 02:47:13 INFO - 'kraken']}, 02:47:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:47:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:47:13 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:47:13 INFO - 'talos_options': ['--disable-stylo'], 02:47:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:47:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:47:13 INFO - 'tests': ['damp', 'tps']}, 02:47:13 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:47:13 INFO - 'talos_options': ['--disable-stylo'], 02:47:13 INFO - 'tests': ['damp', 'tps']}, 02:47:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:47:13 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:47:13 INFO - 'tests': ['dromaeo_dom']}, 02:47:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:47:13 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:47:13 INFO - 'tests': ['basic_compositor_video', 02:47:13 INFO - 'glvideo']}, 02:47:13 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:47:13 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:47:13 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:47:13 INFO - 'talos_options': ['--disable-stylo'], 02:47:13 INFO - 'tests': ['ts_paint_webext', 02:47:13 INFO - 'tp5o_webext']}, 02:47:13 INFO - 'other-e10s': {'tests': ['a11yr', 02:47:13 INFO - 'ts_paint', 02:47:13 INFO - 'tpaint', 02:47:13 INFO - 'sessionrestore', 02:47:13 INFO - 'sessionrestore_many_windows', 02:47:13 INFO - 'sessionrestore_no_auto_restore', 02:47:13 INFO - 'tabpaint']}, 02:47:13 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:47:13 INFO - 'tests': ['a11yr', 02:47:13 INFO - 'ts_paint', 02:47:13 INFO - 'tpaint', 02:47:13 INFO - 'sessionrestore', 02:47:13 INFO - 'sessionrestore_many_windows', 02:47:13 INFO - 'sessionrestore_no_auto_restore', 02:47:13 INFO - 'tabpaint']}, 02:47:13 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 02:47:13 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 02:47:13 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:47:13 INFO - 'tests': ['bloom_basic_singleton']}, 02:47:13 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:47:13 INFO - 'tests': ['bloom_basic']}, 02:47:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:47:13 INFO - 'tsvgr_opacity', 02:47:13 INFO - 'tart', 02:47:13 INFO - 'tscrollx', 02:47:13 INFO - 'tsvg_static']}, 02:47:13 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:47:13 INFO - 'tests': ['tsvgx', 02:47:13 INFO - 'tsvgr_opacity', 02:47:13 INFO - 'tart', 02:47:13 INFO - 'tscrollx', 02:47:13 INFO - 'tsvg_static']}, 02:47:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:47:13 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:47:13 INFO - 'talos_options': ['--disable-stylo'], 02:47:13 INFO - 'tests': ['tp5o']}, 02:47:13 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:47:13 INFO - 'talos_options': ['--mitmproxy', 02:47:13 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:47:13 INFO - '--firstNonBlankPaint'], 02:47:13 INFO - 'tests': ['tp6_google', 02:47:13 INFO - 'tp6_youtube', 02:47:13 INFO - 'tp6_amazon', 02:47:13 INFO - 'tp6_facebook']}, 02:47:13 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:47:13 INFO - 'talos_options': ['--disable-stylo', 02:47:13 INFO - '--mitmproxy', 02:47:13 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:47:13 INFO - '--firstNonBlankPaint'], 02:47:13 INFO - 'tests': ['tp6_google', 02:47:13 INFO - 'tp6_youtube', 02:47:13 INFO - 'tp6_amazon', 02:47:13 INFO - 'tp6_facebook']}, 02:47:13 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:47:13 INFO - 'talos_options': ['--stylo-threads=1', 02:47:13 INFO - '--mitmproxy', 02:47:13 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:47:13 INFO - '--firstNonBlankPaint'], 02:47:13 INFO - 'tests': ['tp6_google', 02:47:13 INFO - 'tp6_youtube', 02:47:13 INFO - 'tp6_amazon', 02:47:13 INFO - 'tp6_facebook']}, 02:47:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:47:13 INFO - 'talos_options': ['--xperf_path', 02:47:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:47:13 INFO - 'tests': ['tp5n']}, 02:47:13 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:47:13 INFO - 'talos_options': ['--disable-stylo', 02:47:13 INFO - '--xperf_path', 02:47:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:47:13 INFO - 'tests': ['tp5n']}}} 02:47:13 INFO - Running post-action listener: _resource_record_post_action 02:47:13 INFO - [mozharness: 2017-09-08 09:47:13.661256Z] Finished populate-webroot step (success) 02:47:13 INFO - [mozharness: 2017-09-08 09:47:13.661345Z] Running create-virtualenv step. 02:47:13 INFO - Running pre-action listener: _resource_record_pre_action 02:47:13 INFO - Running main action method: create_virtualenv 02:47:13 INFO - Creating virtualenv /builds/slave/test/build/venv 02:47:13 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:47:13 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 02:47:13 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:47:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:47:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:47:14 INFO - Installing distribute.............................................................................................................................................................................................done. 02:47:14 INFO - Installing pip...............done. 02:47:14 INFO - Return code: 0 02:47:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:47:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:47:14 INFO - Reading from file tmpfile_stdout 02:47:14 INFO - Output received: 02:47:14 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 02:47:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:47: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')]} 02:47:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47: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')]} 02:47:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2801a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29a4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8090>, '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 0x29a8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a2f90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', '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 02:47:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:47:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:47:14 INFO - Using env: {'DISPLAY': ':0', 02:47:14 INFO - 'HOME': '/home/cltbld', 02:47:14 INFO - 'LANG': 'en_US.UTF-8', 02:47:14 INFO - 'LANGUAGE': 'en_US:en', 02:47:14 INFO - 'LOGNAME': 'cltbld', 02:47:14 INFO - 'MAIL': '/var/mail/cltbld', 02:47:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:14 INFO - 'MOZ_NO_REMOTE': '1', 02:47:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:47:14 INFO - 'NO_EM_RESTART': '1', 02:47:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:47:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:14 INFO - 'PWD': '/builds/slave/test', 02:47:14 INFO - 'SHELL': '/bin/bash', 02:47:14 INFO - 'SHLVL': '1', 02:47:14 INFO - 'TERM': 'linux', 02:47:14 INFO - 'TMOUT': '86400', 02:47:14 INFO - 'USER': 'cltbld', 02:47:14 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', 02:47:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:14 INFO - '_': '/tools/buildbot/bin/python'} 02:47:17 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:47:17 INFO - Downloading/unpacking pip>=1.5 02:47:17 INFO - Running setup.py egg_info for package pip 02:47:17 INFO - warning: no files found matching 'pip/cacert.pem' 02:47:17 INFO - warning: no files found matching '*.html' under directory 'docs' 02:47:17 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:47:17 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:47:17 INFO - Installing collected packages: pip 02:47:17 INFO - Found existing installation: pip 1.1 02:47:17 INFO - Uninstalling pip: 02:47:17 INFO - Successfully uninstalled pip 02:47:17 INFO - Running setup.py install for pip 02:47:17 INFO - warning: no files found matching 'pip/cacert.pem' 02:47:17 INFO - warning: no files found matching '*.html' under directory 'docs' 02:47:17 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:47:17 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:47:17 INFO - Installing pip script to /builds/slave/test/build/venv/bin 02:47:17 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 02:47:17 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 02:47:17 INFO - Successfully installed pip 02:47:17 INFO - Cleaning up... 02:47:17 INFO - Return code: 0 02:47:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:47:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:47:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:47:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x26381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2801a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29a4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8090>, '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 0x29a8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a2f90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', '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 02:47:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:47:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:47:17 INFO - Using env: {'DISPLAY': ':0', 02:47:17 INFO - 'HOME': '/home/cltbld', 02:47:17 INFO - 'LANG': 'en_US.UTF-8', 02:47:17 INFO - 'LANGUAGE': 'en_US:en', 02:47:17 INFO - 'LOGNAME': 'cltbld', 02:47:17 INFO - 'MAIL': '/var/mail/cltbld', 02:47:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:17 INFO - 'MOZ_NO_REMOTE': '1', 02:47:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:47:17 INFO - 'NO_EM_RESTART': '1', 02:47:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:47:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:17 INFO - 'PWD': '/builds/slave/test', 02:47:17 INFO - 'SHELL': '/bin/bash', 02:47:17 INFO - 'SHLVL': '1', 02:47:17 INFO - 'TERM': 'linux', 02:47:17 INFO - 'TMOUT': '86400', 02:47:17 INFO - 'USER': 'cltbld', 02:47:17 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', 02:47:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:17 INFO - '_': '/tools/buildbot/bin/python'} 02:47:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:18 INFO - Downloading/unpacking psutil>=3.1.1 02:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:47:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:47:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:47:19 INFO - Installing collected packages: psutil 02:47:19 INFO - Running setup.py install for psutil 02:47:19 INFO - building 'psutil._psutil_linux' extension 02:47:19 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 02:47:20 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 02:47:21 INFO - building 'psutil._psutil_posix' extension 02:47:21 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 02:47:21 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 02:47:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:47:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:47:21 INFO - Successfully installed psutil 02:47:21 INFO - Cleaning up... 02:47:21 INFO - Return code: 0 02:47:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:47:21 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')]} 02:47:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:21 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')]} 02:47:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:21 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 0x26381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2801a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29a4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8090>, '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 0x29a8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a2f90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', '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 02:47:21 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 02:47:21 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 02:47:21 INFO - Using env: {'DISPLAY': ':0', 02:47:21 INFO - 'HOME': '/home/cltbld', 02:47:21 INFO - 'LANG': 'en_US.UTF-8', 02:47:21 INFO - 'LANGUAGE': 'en_US:en', 02:47:21 INFO - 'LOGNAME': 'cltbld', 02:47:21 INFO - 'MAIL': '/var/mail/cltbld', 02:47:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:21 INFO - 'MOZ_NO_REMOTE': '1', 02:47:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:47:21 INFO - 'NO_EM_RESTART': '1', 02:47:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:47:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:21 INFO - 'PWD': '/builds/slave/test', 02:47:21 INFO - 'SHELL': '/bin/bash', 02:47:21 INFO - 'SHLVL': '1', 02:47:21 INFO - 'TERM': 'linux', 02:47:21 INFO - 'TMOUT': '86400', 02:47:21 INFO - 'USER': 'cltbld', 02:47:21 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', 02:47:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:21 INFO - '_': '/tools/buildbot/bin/python'} 02:47:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:47:21 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 02:47:21 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 02:47:21 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 02:47:21 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 02:47:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:47:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:47:22 INFO - Installing collected packages: mozsystemmonitor 02:47:22 INFO - Running setup.py install for mozsystemmonitor 02:47:22 INFO - Successfully installed mozsystemmonitor 02:47:22 INFO - Cleaning up... 02:47:23 INFO - Return code: 0 02:47:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:47:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:47:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:23 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 0x26381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2801a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29a4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8090>, '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 0x29a8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a2f90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', '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 02:47:23 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 02:47:23 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 02:47:23 INFO - Using env: {'DISPLAY': ':0', 02:47:23 INFO - 'HOME': '/home/cltbld', 02:47:23 INFO - 'LANG': 'en_US.UTF-8', 02:47:23 INFO - 'LANGUAGE': 'en_US:en', 02:47:23 INFO - 'LOGNAME': 'cltbld', 02:47:23 INFO - 'MAIL': '/var/mail/cltbld', 02:47:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:23 INFO - 'MOZ_NO_REMOTE': '1', 02:47:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:47:23 INFO - 'NO_EM_RESTART': '1', 02:47:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:47:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:23 INFO - 'PWD': '/builds/slave/test', 02:47:23 INFO - 'SHELL': '/bin/bash', 02:47:23 INFO - 'SHLVL': '1', 02:47:23 INFO - 'TERM': 'linux', 02:47:23 INFO - 'TMOUT': '86400', 02:47:23 INFO - 'USER': 'cltbld', 02:47:23 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', 02:47:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:23 INFO - '_': '/tools/buildbot/bin/python'} 02:47:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:23 INFO - Downloading/unpacking jsonschema==2.5.1 02:47:23 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 02:47:23 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 02:47:23 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 02:47:23 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 02:47:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:47:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:47:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:24 INFO - Downloading functools32-3.2.3-2.tar.gz 02:47:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:47:24 INFO - warning: no files found matching '*.txt' 02:47:24 INFO - no previously-included directories found matching 'build' 02:47:24 INFO - no previously-included directories found matching 'dist' 02:47:24 INFO - no previously-included directories found matching '.git*' 02:47:24 INFO - Installing collected packages: jsonschema, functools32 02:47:24 INFO - Running setup.py install for functools32 02:47:24 INFO - warning: no files found matching '*.txt' 02:47:24 INFO - no previously-included directories found matching 'build' 02:47:24 INFO - no previously-included directories found matching 'dist' 02:47:24 INFO - no previously-included directories found matching '.git*' 02:47:24 INFO - Successfully installed jsonschema functools32 02:47:24 INFO - Cleaning up... 02:47:24 INFO - Return code: 0 02:47:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:47:24 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')]} 02:47:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:24 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')]} 02:47:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:24 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 0x26381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2801a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29a4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8090>, '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 0x29a8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a2f90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', '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 02:47:24 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 02:47:24 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 02:47:24 INFO - Using env: {'DISPLAY': ':0', 02:47:24 INFO - 'HOME': '/home/cltbld', 02:47:24 INFO - 'LANG': 'en_US.UTF-8', 02:47:24 INFO - 'LANGUAGE': 'en_US:en', 02:47:24 INFO - 'LOGNAME': 'cltbld', 02:47:24 INFO - 'MAIL': '/var/mail/cltbld', 02:47:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:24 INFO - 'MOZ_NO_REMOTE': '1', 02:47:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:47:24 INFO - 'NO_EM_RESTART': '1', 02:47:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:47:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:24 INFO - 'PWD': '/builds/slave/test', 02:47:24 INFO - 'SHELL': '/bin/bash', 02:47:24 INFO - 'SHLVL': '1', 02:47:24 INFO - 'TERM': 'linux', 02:47:24 INFO - 'TMOUT': '86400', 02:47:24 INFO - 'USER': 'cltbld', 02:47:24 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', 02:47:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:24 INFO - '_': '/tools/buildbot/bin/python'} 02:47:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:47:24 INFO - Cleaning up... 02:47:24 INFO - Return code: 0 02:47:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:47:24 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')]} 02:47:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:24 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')]} 02:47:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:24 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 0x26381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2801a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29a4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8090>, '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 0x29a8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a2f90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', '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 02:47:24 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 02:47:24 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 02:47:24 INFO - Using env: {'DISPLAY': ':0', 02:47:24 INFO - 'HOME': '/home/cltbld', 02:47:24 INFO - 'LANG': 'en_US.UTF-8', 02:47:24 INFO - 'LANGUAGE': 'en_US:en', 02:47:24 INFO - 'LOGNAME': 'cltbld', 02:47:24 INFO - 'MAIL': '/var/mail/cltbld', 02:47:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:24 INFO - 'MOZ_NO_REMOTE': '1', 02:47:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:47:24 INFO - 'NO_EM_RESTART': '1', 02:47:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:47:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:24 INFO - 'PWD': '/builds/slave/test', 02:47:24 INFO - 'SHELL': '/bin/bash', 02:47:24 INFO - 'SHLVL': '1', 02:47:24 INFO - 'TERM': 'linux', 02:47:24 INFO - 'TMOUT': '86400', 02:47:24 INFO - 'USER': 'cltbld', 02:47:24 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', 02:47:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:24 INFO - '_': '/tools/buildbot/bin/python'} 02:47:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:25 INFO - Downloading/unpacking blobuploader==1.2.4 02:47:25 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 02:47:25 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 02:47:25 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 02:47:25 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 02:47:26 INFO - Downloading blobuploader-1.2.4.tar.gz 02:47:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:47:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:47:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:26 INFO - Downloading docopt-0.6.1.tar.gz 02:47:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:47:26 INFO - Installing collected packages: blobuploader, requests, docopt 02:47:26 INFO - Running setup.py install for blobuploader 02:47:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:47:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:47:26 INFO - Running setup.py install for requests 02:47:27 INFO - Running setup.py install for docopt 02:47:27 INFO - Successfully installed blobuploader requests docopt 02:47:27 INFO - Cleaning up... 02:47:27 INFO - Return code: 0 02:47:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:47:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:47:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:27 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 0x26381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2801a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29a4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8090>, '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 0x29a8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a2f90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', '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 02:47:27 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 02:47:27 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 02:47:27 INFO - Using env: {'DISPLAY': ':0', 02:47:27 INFO - 'HOME': '/home/cltbld', 02:47:27 INFO - 'LANG': 'en_US.UTF-8', 02:47:27 INFO - 'LANGUAGE': 'en_US:en', 02:47:27 INFO - 'LOGNAME': 'cltbld', 02:47:27 INFO - 'MAIL': '/var/mail/cltbld', 02:47:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:27 INFO - 'MOZ_NO_REMOTE': '1', 02:47:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:47:27 INFO - 'NO_EM_RESTART': '1', 02:47:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:47:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:27 INFO - 'PWD': '/builds/slave/test', 02:47:27 INFO - 'SHELL': '/bin/bash', 02:47:27 INFO - 'SHLVL': '1', 02:47:27 INFO - 'TERM': 'linux', 02:47:27 INFO - 'TMOUT': '86400', 02:47:27 INFO - 'USER': 'cltbld', 02:47:27 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', 02:47:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:27 INFO - '_': '/tools/buildbot/bin/python'} 02:47:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:47:27 INFO - Running setup.py (path:/tmp/pip-GEke8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:47:27 INFO - Running setup.py (path:/tmp/pip-w6GEC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:47:27 INFO - Running setup.py (path:/tmp/pip-T6vDYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:47:27 INFO - Running setup.py (path:/tmp/pip-157ekF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:47:27 INFO - Running setup.py (path:/tmp/pip-8PRzwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:47:28 INFO - Running setup.py (path:/tmp/pip-oqCk4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:47:28 INFO - Running setup.py (path:/tmp/pip-ZYyZO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:47:28 INFO - Running setup.py (path:/tmp/pip-JbZsry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:47:28 INFO - Running setup.py (path:/tmp/pip-YEO0qV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:47:28 INFO - Running setup.py (path:/tmp/pip-mOlsCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:47:28 INFO - Running setup.py (path:/tmp/pip-p4Pk5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:47:28 INFO - Running setup.py (path:/tmp/pip-rSRQOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:47:28 INFO - Running setup.py (path:/tmp/pip-1LpG1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:47:28 INFO - Running setup.py (path:/tmp/pip-IveZyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:47:28 INFO - Running setup.py (path:/tmp/pip-4MH8d4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:47:28 INFO - Running setup.py (path:/tmp/pip-Y8dhQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:47:28 INFO - Running setup.py (path:/tmp/pip-Xwc6_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:47:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:47:28 INFO - Running setup.py install for manifestparser 02:47:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:47:28 INFO - Running setup.py install for mozcrash 02:47:28 INFO - Running setup.py install for mozdebug 02:47:29 INFO - Running setup.py install for mozdevice 02:47:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:47:29 INFO - Running setup.py install for mozfile 02:47:29 INFO - Running setup.py install for mozhttpd 02:47:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:47:29 INFO - Running setup.py install for mozinfo 02:47:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:47:29 INFO - Running setup.py install for mozInstall 02:47:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:47:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:47:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:47:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:47:29 INFO - Running setup.py install for mozleak 02:47:29 INFO - Running setup.py install for mozlog 02:47:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:47:29 INFO - Running setup.py install for moznetwork 02:47:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:47:29 INFO - Running setup.py install for mozprocess 02:47:30 INFO - Running setup.py install for mozprofile 02:47:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:47:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:47:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:47:30 INFO - Running setup.py install for mozrunner 02:47:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:47:30 INFO - Running setup.py install for mozscreenshot 02:47:30 INFO - Running setup.py install for moztest 02:47:30 INFO - Running setup.py install for mozversion 02:47:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:47:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:47:30 INFO - Cleaning up... 02:47:30 INFO - Return code: 0 02:47:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:47: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')]} 02:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47: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')]} 02:47:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:30 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 0x26381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2801a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29a4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8090>, '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 0x29a8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a2f90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', '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 02:47:30 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 02:47:30 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 02:47:30 INFO - Using env: {'DISPLAY': ':0', 02:47:30 INFO - 'HOME': '/home/cltbld', 02:47:30 INFO - 'LANG': 'en_US.UTF-8', 02:47:30 INFO - 'LANGUAGE': 'en_US:en', 02:47:30 INFO - 'LOGNAME': 'cltbld', 02:47:30 INFO - 'MAIL': '/var/mail/cltbld', 02:47:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:30 INFO - 'MOZ_NO_REMOTE': '1', 02:47:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:47:30 INFO - 'NO_EM_RESTART': '1', 02:47:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:47:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:30 INFO - 'PWD': '/builds/slave/test', 02:47:30 INFO - 'SHELL': '/bin/bash', 02:47:30 INFO - 'SHLVL': '1', 02:47:30 INFO - 'TERM': 'linux', 02:47:30 INFO - 'TMOUT': '86400', 02:47:30 INFO - 'USER': 'cltbld', 02:47:30 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', 02:47:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:30 INFO - '_': '/tools/buildbot/bin/python'} 02:47:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:47:30 INFO - Running setup.py (path:/tmp/pip-jqULCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:47:30 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)) 02:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:47:30 INFO - Running setup.py (path:/tmp/pip-SRqkAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:47:30 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)) 02:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:47:30 INFO - Running setup.py (path:/tmp/pip-u1jENT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:47:30 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)) 02:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:47:30 INFO - Running setup.py (path:/tmp/pip-_S_I_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:47:31 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)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:47:31 INFO - Running setup.py (path:/tmp/pip-6Qq29M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:47:31 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)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:47:31 INFO - Running setup.py (path:/tmp/pip-cdlvOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:47:31 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)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:47:31 INFO - Running setup.py (path:/tmp/pip-LlNMkd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:47:31 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)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:47:31 INFO - Running setup.py (path:/tmp/pip-9zbSkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:47:31 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)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:47:31 INFO - Running setup.py (path:/tmp/pip-LXuLkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:47:31 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)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:47:31 INFO - Running setup.py (path:/tmp/pip-WxlkHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:47:31 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)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:47:31 INFO - Running setup.py (path:/tmp/pip-JPfVR5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:47:31 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)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:47:31 INFO - Running setup.py (path:/tmp/pip-jkjTTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:47:31 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)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:47:31 INFO - Running setup.py (path:/tmp/pip-wTmFIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:47:31 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)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:47:31 INFO - Running setup.py (path:/tmp/pip-OXvXWr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:47:31 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)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:47:31 INFO - Running setup.py (path:/tmp/pip-uz72jd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:47:31 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)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:47:31 INFO - Running setup.py (path:/tmp/pip-Ev3r8H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:47:31 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)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:47:31 INFO - Running setup.py (path:/tmp/pip-uw7u9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:47:31 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)) 02:47:31 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)) 02:47:31 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)) 02:47:31 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)) 02:47:31 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)) 02:47:31 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)) 02:47:31 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)) 02:47:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:47:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:33 INFO - Downloading blessings-1.6.tar.gz 02:47:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:47:33 INFO - Installing collected packages: blessings 02:47:33 INFO - Running setup.py install for blessings 02:47:33 INFO - Successfully installed blessings 02:47:33 INFO - Cleaning up... 02:47:33 INFO - Return code: 0 02:47:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:47:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:47:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:47:33 INFO - Reading from file tmpfile_stdout 02:47:33 INFO - Current package versions: 02:47:33 INFO - argparse == 1.2.1 02:47:33 INFO - blessings == 1.6 02:47:33 INFO - blobuploader == 1.2.4 02:47:33 INFO - docopt == 0.6.1 02:47:33 INFO - functools32 == 3.2.3-2 02:47:33 INFO - jsonschema == 2.5.1 02:47:33 INFO - manifestparser == 1.1 02:47:33 INFO - mozInstall == 1.13 02:47:33 INFO - mozcrash == 1.0 02:47:33 INFO - mozdebug == 0.1 02:47:33 INFO - mozdevice == 0.51 02:47:33 INFO - mozfile == 1.2 02:47:33 INFO - mozhttpd == 0.7 02:47:33 INFO - mozinfo == 0.10 02:47:33 INFO - mozleak == 0.1 02:47:33 INFO - mozlog == 3.5 02:47:33 INFO - moznetwork == 0.27 02:47:33 INFO - mozprocess == 0.25 02:47:33 INFO - mozprofile == 0.28 02:47:33 INFO - mozrunner == 6.13 02:47:33 INFO - mozscreenshot == 0.1 02:47:33 INFO - mozsystemmonitor == 0.3 02:47:33 INFO - moztest == 0.8 02:47:33 INFO - mozversion == 1.4 02:47:33 INFO - psutil == 3.1.1 02:47:33 INFO - requests == 1.2.3 02:47:33 INFO - wsgiref == 0.1.2 02:47:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:47: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')]} 02:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47: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')]} 02:47:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:33 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 0x26381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2801a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29a4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8090>, '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 0x29a8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a2f90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', '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 02:47:33 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 02:47:33 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 02:47:33 INFO - Using env: {'DISPLAY': ':0', 02:47:33 INFO - 'HOME': '/home/cltbld', 02:47:33 INFO - 'LANG': 'en_US.UTF-8', 02:47:33 INFO - 'LANGUAGE': 'en_US:en', 02:47:33 INFO - 'LOGNAME': 'cltbld', 02:47:33 INFO - 'MAIL': '/var/mail/cltbld', 02:47:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:33 INFO - 'MOZ_NO_REMOTE': '1', 02:47:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:47:33 INFO - 'NO_EM_RESTART': '1', 02:47:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:47:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:33 INFO - 'PWD': '/builds/slave/test', 02:47:33 INFO - 'SHELL': '/bin/bash', 02:47:33 INFO - 'SHLVL': '1', 02:47:33 INFO - 'TERM': 'linux', 02:47:33 INFO - 'TMOUT': '86400', 02:47:33 INFO - 'USER': 'cltbld', 02:47:33 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', 02:47:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:33 INFO - '_': '/tools/buildbot/bin/python'} 02:47:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:33 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)) 02:47:33 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)) 02:47:33 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)) 02:47:33 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)) 02:47:33 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)) 02:47:33 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)) 02:47:33 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)) 02:47:33 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)) 02:47:33 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)) 02:47:33 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:47: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 02:47: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 02:47: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 02:47: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 02:47:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:47:35 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)) 02:47:35 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)) 02:47:35 INFO - Installing collected packages: simplejson 02:47:35 INFO - Running setup.py install for simplejson 02:47:35 INFO - building 'simplejson._speedups' extension 02:47:35 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 02:47:35 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 02:47:36 INFO - Successfully installed simplejson 02:47:36 INFO - Cleaning up... 02:47:36 INFO - Return code: 0 02:47:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:47:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:47:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2801a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29a4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8090>, '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 0x29a8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a2f90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', '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 02:47:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:47:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:47:36 INFO - Using env: {'DISPLAY': ':0', 02:47:36 INFO - 'HOME': '/home/cltbld', 02:47:36 INFO - 'LANG': 'en_US.UTF-8', 02:47:36 INFO - 'LANGUAGE': 'en_US:en', 02:47:36 INFO - 'LOGNAME': 'cltbld', 02:47:36 INFO - 'MAIL': '/var/mail/cltbld', 02:47:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:36 INFO - 'MOZ_NO_REMOTE': '1', 02:47:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:47:36 INFO - 'NO_EM_RESTART': '1', 02:47:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:47:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:36 INFO - 'PWD': '/builds/slave/test', 02:47:36 INFO - 'SHELL': '/bin/bash', 02:47:36 INFO - 'SHLVL': '1', 02:47:36 INFO - 'TERM': 'linux', 02:47:36 INFO - 'TMOUT': '86400', 02:47:36 INFO - 'USER': 'cltbld', 02:47:36 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', 02:47:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:36 INFO - '_': '/tools/buildbot/bin/python'} 02:47:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:47:36 INFO - Cleaning up... 02:47:36 INFO - Return code: 0 02:47:36 INFO - Running post-action listener: _resource_record_post_action 02:47:36 INFO - Running post-action listener: _start_resource_monitoring 02:47:36 INFO - Starting resource monitoring. 02:47:36 INFO - [mozharness: 2017-09-08 09:47:36.355248Z] Finished create-virtualenv step (success) 02:47:36 INFO - [mozharness: 2017-09-08 09:47:36.355707Z] Running install step. 02:47:36 INFO - Running pre-action listener: _resource_record_pre_action 02:47:36 INFO - Running main action method: install 02:47:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:47:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:47:36 INFO - Reading from file tmpfile_stdout 02:47:36 INFO - Detecting whether we're running mozinstall >=1.0... 02:47:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:47:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:47:36 INFO - Reading from file tmpfile_stdout 02:47:36 INFO - Output received: 02:47:36 INFO - Usage: mozinstall [options] installer 02:47:36 INFO - Options: 02:47:36 INFO - -h, --help show this help message and exit 02:47:36 INFO - -d DEST, --destination=DEST 02:47:36 INFO - Directory to install application into. [default: 02:47:36 INFO - "/builds/slave/test"] 02:47:36 INFO - --app=APP Application being installed. [default: firefox] 02:47:36 INFO - mkdir: /builds/slave/test/build/application 02:47:36 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'] 02:47:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:47:53 INFO - Reading from file tmpfile_stdout 02:47:53 INFO - Output received: 02:47:53 INFO - /builds/slave/test/build/application/firefox/firefox 02:47:53 INFO - Running post-action listener: _resource_record_post_action 02:47:53 INFO - [mozharness: 2017-09-08 09:47:53.231577Z] Finished install step (success) 02:47:53 INFO - [mozharness: 2017-09-08 09:47:53.231826Z] Running setup-mitmproxy step. 02:47:53 INFO - Running pre-action listener: _resource_record_pre_action 02:47:53 INFO - Running main action method: setup_mitmproxy 02:47:53 INFO - Skipping: mitmproxy is not required 02:47:53 INFO - Running post-action listener: _resource_record_post_action 02:47:53 INFO - [mozharness: 2017-09-08 09:47:53.232963Z] Finished setup-mitmproxy step (success) 02:47:53 INFO - [mozharness: 2017-09-08 09:47:53.233307Z] Running run-tests step. 02:47:53 INFO - Running pre-action listener: _resource_record_pre_action 02:47:53 INFO - Running pre-action listener: _set_gcov_prefix 02:47:53 INFO - Running main action method: run_tests 02:47:53 WARNING - Try message not found. 02:47:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:47:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:47:53 INFO - Python 2.7.3 02:47:53 INFO - Return code: 0 02:47:53 INFO - grabbing minidump binary from tooltool 02:47:53 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')]} 02:47:53 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 0x29a8090>, '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 0x29a8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a2f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:47:53 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 02:47:53 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 02:47:53 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 02:47:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:47:53 INFO - Return code: 0 02:47:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:47:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:47:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:47:53 INFO - ENV: RUST_BACKTRACE is now full 02:47:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:47:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:47:53 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/F69fQAZfRECOnrdgihUWdw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 02:47:53 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/F69fQAZfRECOnrdgihUWdw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-041 --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 02:47:53 INFO - Using env: {'DISPLAY': ':0', 02:47:53 INFO - 'HOME': '/home/cltbld', 02:47:53 INFO - 'LANG': 'en_US.UTF-8', 02:47:53 INFO - 'LANGUAGE': 'en_US:en', 02:47:53 INFO - 'LOGNAME': 'cltbld', 02:47:53 INFO - 'MAIL': '/var/mail/cltbld', 02:47:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:47:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:47:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:53 INFO - 'MOZ_NO_REMOTE': '1', 02:47:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:47:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:47:53 INFO - 'NO_EM_RESTART': '1', 02:47:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:47:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:53 INFO - 'PWD': '/builds/slave/test', 02:47:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:47:53 INFO - 'RUST_BACKTRACE': 'full', 02:47:53 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:47:53 INFO - 'SHELL': '/bin/bash', 02:47:53 INFO - 'SHLVL': '1', 02:47:53 INFO - 'STYLO_FORCE_ENABLED': '1', 02:47:53 INFO - 'STYLO_THREADS': '1', 02:47:53 INFO - 'TERM': 'linux', 02:47:53 INFO - 'TMOUT': '86400', 02:47:53 INFO - 'USER': 'cltbld', 02:47:53 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', 02:47:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:53 INFO - '_': '/tools/buildbot/bin/python'} 02:47:53 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/F69fQAZfRECOnrdgihUWdw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 02:47:53 INFO - mozversion application_buildid: 20170908085353 02:47:53 INFO - mozversion application_changeset: fd1ab37308c5cf0e1fd2471708f53d8e6ddc69aa 02:47:53 INFO - mozversion application_display_name: Nightly 02:47:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:47:53 INFO - mozversion application_name: Firefox 02:47:53 INFO - mozversion application_remotingname: firefox 02:47:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:47:53 INFO - mozversion application_vendor: Mozilla 02:47:53 INFO - mozversion application_version: 57.0a1 02:47:53 INFO - mozversion platform_buildid: 20170908085353 02:47:53 INFO - mozversion platform_changeset: fd1ab37308c5cf0e1fd2471708f53d8e6ddc69aa 02:47:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:47:53 INFO - mozversion platform_version: 57.0a1 02:47:53 INFO - using testdate: 1504864073 02:47:53 INFO - actual date: 1504864073 02:47:53 INFO - starting webserver on 'localhost:35266' 02:47:53 INFO - SUITE-START | Running 2 tests 02:47:53 INFO - TEST-START | dromaeo_css 02:47:53 INFO - Initialising browser for dromaeo_css test... 02:47:53 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:35266/getInfo.html -profile /tmp/tmpm6m8p0/profile 02:47:53 INFO - TEST-INFO | started process 3525 (/builds/slave/test/build/application/firefox/firefox http://localhost:35266/getInfo.html) 02:48:01 INFO - TEST-INFO | 3525: exit 0 02:48:01 INFO - Browser initialized. 02:48:01 INFO - Running cycle 1/1 for dromaeo_css test... 02:48:01 INFO - Using env: {'DISPLAY': ':0', 02:48:01 INFO - 'HOME': '/home/cltbld', 02:48:01 INFO - 'JSGC_DISABLE_POISONING': '1', 02:48:01 INFO - 'LANG': 'en_US.UTF-8', 02:48:01 INFO - 'LANGUAGE': 'en_US:en', 02:48:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:48:01 INFO - 'LOGNAME': 'cltbld', 02:48:01 INFO - 'MAIL': '/var/mail/cltbld', 02:48:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:48:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:48:01 INFO - 'MOZ_CRASHREPORTER': '1', 02:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:48:01 INFO - 'MOZ_NO_REMOTE': '1', 02:48:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:48:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:48:01 INFO - 'NO_EM_RESTART': '1', 02:48:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:01 INFO - 'PWD': '/builds/slave/test', 02:48:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:48:01 INFO - 'RUST_BACKTRACE': 'full', 02:48:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:48:01 INFO - 'SHELL': '/bin/bash', 02:48:01 INFO - 'SHLVL': '1', 02:48:01 INFO - 'STYLO_FORCE_ENABLED': '1', 02:48:01 INFO - 'STYLO_THREADS': '1', 02:48:01 INFO - 'TERM': 'linux', 02:48:01 INFO - 'TMOUT': '86400', 02:48:01 INFO - 'USER': 'cltbld', 02:48:01 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', 02:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:01 INFO - '_': '/tools/buildbot/bin/python'} 02:48:01 INFO - TEST-INFO | started process 3718 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpm6m8p0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:48:02 INFO - PID 3718 | 02:48:02 INFO - PID 3718 | (/builds/slave/test/build/application/firefox/firefox:3769): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:48:02 INFO - PID 3718 | 02:48:12 INFO - PID 3718 | RSS: Main: 170393600 02:48:12 INFO - PID 3718 | 02:51:10 INFO - PID 3718 | Cycle 1(1): loaded http://localhost:35266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35266/tests/dromaeo/cssquery-ext.html) 02:51:11 INFO - PID 3718 | RSS: Main: 174100480 02:51:11 INFO - PID 3718 | 02:54:12 INFO - PID 3718 | Cycle 1(1): loaded http://localhost:35266/tests/dromaeo/cssquery-ext.html (next: http://localhost:35266/tests/dromaeo/cssquery-jquery.html) 02:54:13 INFO - PID 3718 | RSS: Main: 168333312 02:54:13 INFO - PID 3718 | 02:57:15 INFO - PID 3718 | Cycle 1(1): loaded http://localhost:35266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35266/tests/dromaeo/cssquery-mootools.html) 02:57:15 INFO - PID 3718 | RSS: Main: 172724224 02:57:15 INFO - PID 3718 | 03:00:17 INFO - PID 3718 | Cycle 1(1): loaded http://localhost:35266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35266/tests/dromaeo/cssquery-prototype.html) 03:00:17 INFO - PID 3718 | RSS: Main: 171077632 03:00:17 INFO - PID 3718 | 03:03:21 INFO - PID 3718 | Cycle 1(1): loaded http://localhost:35266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35266/tests/dromaeo/cssquery-yui.html) 03:03:21 INFO - PID 3718 | RSS: Main: 172314624 03:03:21 INFO - PID 3718 | 03:06:23 INFO - PID 3718 | Cycle 1(1): loaded http://localhost:35266/tests/dromaeo/cssquery-yui.html (next: http://localhost:35266/tests/dromaeo/cssquery-dojo.html) 03:06:23 INFO - PID 3718 | RSS: Main: 174186496 03:06:23 INFO - PID 3718 | 03:06:23 INFO - PID 3718 | __start_tp_report 03:06:23 INFO - PID 3718 | _x_x_mozilla_page_load 03:06:23 INFO - PID 3718 | _x_x_mozilla_page_load_details 03:06:23 INFO - PID 3718 | |i|pagename|runs| 03:06:23 INFO - PID 3718 | |0;dojo.html;3095.5666206731053;3110.4712198926186;3117.4388610050196;3122.625284965803;3150.747940164787;6170.352113028123;6283.7486500540035;6362;6378.776742814002;6395.776147834827;6313.084602732601;6349.968250158754;6361;6375.203099612549;6383.872322553558;5832.475077243047;5838.474537291643;5880.323762767293;5893.6463812171405;5927.644341339534;5799.681017544033;5802.100674395476;5855.326637436686;5873.207117039187;5879.559033072515;5553.527950124204;5609.3268807743125;5639.8590035249035;5648.491635752802;5802.854928626797;5729.6562206267345;5737.540996720252;5784.508316793035;5794.623349482313;5802.274715660543;5674.063779476382;5701.657900525982;5749.971250143764;5755.827325180251;5768.653880767168;6186.536009799238;6262;6267.71795269214;6292.968535157295;6302.87394252117;6700.463962882957;6714.932850671479;6752.358525940028;6809.182898052265;6837.829054273633;6780.457563394916;6825.897611535831;6843.3840954314355;6917.13535808024;6923.7576684816295;6347.174867267272;6370.649614271213;6386.584871983307;6395.584287021329;6403.48772098231;6940.09778728762;6956.79129626112;7008.964955175294;7037.120359955005;7064.222935477093;6418.6469744164515;6577.473802095917;6645.169353830771;6651.700673469705;6741.258461569223;6653.301403352675;6666.133402657623;6675.899861502081;6677.699503522354;6737.461003119739;6714.462842972551;6735.225449073321;6789.049533065375;6795.388415042571;6808.795736127924;6754.088198093293;6754.662266886636;6833.077534532775;6842.178938527409;6847.246802851682;5171.146760784503;5250.448702886142;5272.868178295496;5273.683578985273;5324.760385782649;4576.908461830745;4609.055143695535;4609.953900460952;4687.179743544866;4718.292256161534;5763.135529670583;5814.592978491465;5829.300483941955;5832.416758324219;5835.941640583625;6746.156730408699;6767.627780472121;6775.390214880685;6860.588364698134;6871.690773915186;5634.549236061105;5712.171735098471;5733.627314224562;5766.94233057664;5813.941860581424;5809.767609295665;5846.181534585162;5873.236479257669;5878.735456904449;5881.7647294107755;6418.229812422446;6506.642134682638;6509.153810004669;6600.207975043026;6600.702968366435;6639.170103737033;6740.764073257413;6793.354631310018;6878.759243426456;6886.758963436256;6383.489320854414;6465.256495502889;6479.060536222127;6489.480841532761;6503.122078519339;6703.826830304774;6706.161729783777;6720.260771315249;6768.627725475146;6812.080369150101;6639.767608133692;6805.217399999064;6833.384995350442;6900.30996900326;6927.27263637331;6492.188476440445;6502.707378168088;6510.44661203803;6519.967400162969;6528.151340325917;6462.095306657166;6539.509536784665;6543.1821022372205;6577.079208910661;6589;6445.903311450238;6538.346165383614;6582.407583317525;6609.669516524058;6631.568948018364;6592.208934927839;6643;6706.195256569243;6710.094137291403;6725.73097076131;6323.715432805443;6355.523335749745;6370.490360771035;6445.452136568444;6446.387593178641 03:06:23 INFO - PID 3718 | |1;ext.html;815.5636734347122;841.495102938237;869.0266901070802;869.5043825019737;870.8824308718321;39728.40407393892;40585.3912191317;40690;40697.18605627893;40704.59295407049;37503.81248093756;37810.24379512408;37891.24217515655;37893.62106378935;37911.810440947826;7046.7181312747425;7125.39434148098;7133.144022717255;7140.39306658932;7148.928510714904;7048.5418447800985;7109.537880037807;7124.893126603105;7126.358627723504;7151.821204469878;7104.644767761617;7119.466040046991;7128.215896251409;7129.5365801223015;7137.072180616513;7680.961595192016;7915.722949696762;7958.562279074648;7964.482308649977;7974.720884769005;7735;7762.223777622249;7763.767086987399;7771.805704857367;7781.922180778176;37598.43602345967;38238;38634.61365386338;38855.8057209715;38880.22239555221;3449.137715571107;3485.5468789057513;3486.337595856779;3491.039964009892;3500.177458297298;32001;32053.358932821448;32116.518252226237;32179;32203;5139.820106296262;5169.638125331228;5170.741462926839;5192.1952097424955;5236.7119808410525;179255;179702;179991;180192.9999999987;181151;46843;46889.53110468852;46891.76554117208;46949;47011;48066.5193348062;48587.51412485901;48617.27074093891;48666.513334866904;48693.75653121783;15897.046177229404;16128.838711612734;16131.516054518383;16133.112678802543;16142.03147811135;13394.397252123677;13457.730845383037;13470.326483675777;13470.865291346961;13493.66265843342;13661.38523766413;13736.381862816197;13736.93131534336;13813.723725525433;13822.585322440344;5416.2688037114485;5449.536789372868;5533.280673512418;5536.7785288588;5579.776808927598;818.4516374029415;854.410456784829;861.8104017116225;881.5944665453964;893.7944272817289;18674.439766807016;18689.81310186908;18737.250509979447;18766.718499222523;18906.716399254023;956.0726095687172;967.5645959318335;998.8301988661945;999.3853779925294;1006.5369929832212;9908.306418550632;9957.00429957013;10072.345297555636;10114.089731924016;10200.591976320864;221499;222404;222844;223037;223204;45455.09089818185;46230;50618;56786.71606642023;57202;1404.817373741407;1440.819897512811;1442.5600191941558;1456.941722331095;1457.1038811131077;26814.86592567025;27415.451690966467;27486.862565687043;27717.584236236875;27735.30661733392;38603.80698096604;38630.80684596559;38933.2213355737;38976;38985.22029559336;209173;210866;211140;211204;211395;33907.66092339045;34948.47577286394;35328;35337.82331088328;35534.822325888206;44752.77623611861;44766.104677905605;44983.55016449794;45055;45292.54707452883;31620.525692114177;32082.83958580192;32140;32295.08605016083;32551.348973019933;32029.35941281208;32117.839410802797;32136.517952231203;32204.355912882078;32441.837790810896;35372.29255414929;35760.28479430449;36433.45349819702;36604.63395366012;36623;30606.846965765028;30751.384972300875;31082.22294442657;31121.8443907779;31364.686353136178;22390.888045559666;22733.886330568243;22875.656865146702;22921.312360629207;23006.42483937915 03:06:23 INFO - PID 3718 | |2;jquery.html;553.2586334312022;556.3657430529197;562.5471495446166;563.963342382745;566.8398677373642;21645.45886352842;22400.88799556002;22492.55014899703;22514.549709005852;22524.88737556314;22749.4312642184;24211.39471513213;24589.38526536833;24591.75408245917;24598.26205213842;6255.155554000208;6371.713272902707;6420.133282006939;6421.165248517709;6424.807255782334;6294;6320.74717011319;6346.365363463639;6430.260520040209;6431.453326467233;6582.539222254444;6583.5062370322175;6584.176977877765;6585.934140658604;6586.670666466657;6592.9340706593275;6594.373534514213;6595.406413422791;6599.505037122235;6599.769008084742;5649.12438572022;5931.555133365015;5948.078047902582;6099.756009759604;6113.84715382119;27850.721492785015;28451.857740711162;28491.145265642062;28517.429651406856;28602.284942876388;2012.8691635043822;2031.0555591649954;2041.173324803442;2044.0188709419563;2044.4582185720797;18481.907590462095;19021.809781902142;19101.80898191014;19308.131134098858;19309.61380772391;2229.6989906362596;2276.612975794119;2364.7517010713973;2364.97635023651;2371.537550177716;114011;119326.80673193243;129320.35339823335;132881.6711832879;133638.33180834033;14148.434062637585;14301.213433261062;14451.242303228168;14530.346134423977;14627.561173164822;22093.558128837332;22096;22120.2257920972;22130.557388852132;22218.11127554912;9925.156361709336;10110.342827716322;10167.898321016843;10276.434796086138;10277.640282590073;9530.142287194185;9533.52332383378;9539.61841526345;9614.71155865325;9620.95189524048;8793.296536277083;8975.326850486239;9027.322950778718;9034.14175653312;9068.183863452321;2634.7628713415884;2658.1626787561854;2670.092168662625;2687.341601307653;2771.7228277172526;931.6459745296744;960.5677445149711;962.1148543340144;967.4388854464394;974.878140232471;18378;18601.34895278686;18632.90683546601;18922.526936826413;19024.42926712201;984.6849008317413;1005.7938122684832;1016.1007508355159;1022.8210063238903;1024.156518267116;10205.285630005978;10396.948015260026;10404.11565016982;10474.109700675375;10627.202959778047;420962;429198;431884;433163;436490;107199.46400268105;109555;109653;113477;113650;1109.5395410904546;1141.6357452843772;1210.2194084815244;1226.343906010298;1233.8827811358087;20459.28392506255;20486.38540843777;20604.896975515025;20608.48478788042;20819.37541873746;56410.153847691494;57720.71139644275;57822.4217757817;57953;57972;69666.65166674134;70189.64905175442;70462.64768676124;70627.29372706208;71123.28876711166;14086.36611352512;14452.855471445151;14556;14576.635584110481;14578.708425831635;22173;22349.888250558644;22677.659835102157;22728;22766.886165569067;17603.207855646782;17906.194221259815;17947.641047179244;17999.820001800337;18264.178111984726;14646.194459304841;14707.55877323682;14992.550223493312;15032.699346013238;15931.840681593036;18420.35528756487;18426.170822313296;18623.255069797055;18698.719519206952;18975.14611842498;13378.93310533441;14008.649783755487;14103.435862565382;14132.081414708011;14145.222012789396;17513.38703145435;17770.466885993443;17816.376426824998;17843;18163.091845407413 03:06:23 INFO - PID 3718 | |3;mootools.html;1306.581893793986;1389.5067251125847;1397.1477398786737;1404.6137312239139;1413.2651021468837;14838.332275047622;15460.304286307115;15486.148261845594;15506.14716190609;15509.146996915179;15202.391904323842;15281.617959551017;15309.846901530982;15349.462768803094;15363.92318038411;2648.0731743889583;2681.691605465372;2729.727027297274;2731.2762118038736;2737.178846346098;2720.279126031603;2739.4521095780824;2752.6972033076445;2757.131503576367;2759.903403380882;2656.4687062587464;2705.580635001568;2847.857607119646;2848.387596666716;2848.6154369160104;2780.8748606312765;2794.748472637452;2807.719228077186;2816.7324104210065;2833.3766571354267;2576.600626902833;2708.5801700736415;2710.715374885648;2736.1244401791437;2755.9448811023667;15091.54725358241;15145.697086058328;15152.772708409273;15242.314095865713;15248.923755381264;7878.724244651467;8201.589920503951;8204;8221.588920553948;8226.835463290701;5215.24378965048;5433.809816656438;5464.672119672832;5515.944840551583;5553.944460555424;1950.1906708716008;1968.1340210307562;1978.9208431662717;2004.4487765864358;2016.3648450737971;26005.869970650216;26169.869150654315;26172.214833555023;26238.737612624012;26313.736862631322;6647.268800431949;6760;6802.163415877685;7035.155781306276;7040.823979400454;16681.749773753403;17065.7440138398;17434.12829358527;17481.03854288001;17497.91251043762;7746.612669366622;7997.800054998555;7999.720009799746;8003.559804210708;8004.679812807539;7189.676464559107;7309.086364204474;7356.558606483521;7359.153697324876;7394.075740532434;4947.925781113287;5158.246196293203;5160.819371321985;5180.300659410931;5199.220116982483;1515.2272341106116;1516.7724841273678;1526.3207872496855;1541.714782765192;1550.5115888495088;888.3337496877342;900.9234215091659;918.2332752151895;921.3412410126791;928.4429342394509;7551.169371369255;7572.507786993828;7600.961995189989;7705.113911900091;7714.9228507715325;663.6847497438774;681.6966449929832;682.5188242289174;706.908101946754;708.0193931404938;4221;4853.830115945995;5211.270422140903;5233.973830130901;5380.381256155514;21187.68218476724;21459.67810482844;21656.56686866254;21985.890070549543;22051.779482204973;21307.14771409126;21431.89284053601;21615.67576486354;21769.238076667556;21819;11473.196876218752;11493.885061149282;11523;11577.247478913843;11660.825087623693;8254.669813207645;8280.627371768165;8301.335893128657;8387.580620968805;8389.286910612665;18410.631787364444;18942;19008.809911900706;19105;19367.225310987404;18624;18990;19327.51681207981;19494.31769888047;19683.803161968197;10935.398553079656;11027.117830573696;11037.613683521038;11105.444727763741;11122.555097796321;13650.726985460036;13788.103773254707;13792.586222413343;13793.724125517634;13849.099808512414;10767.6231331903;10792.514336854703;10878.836817447585;11088.445577721242;11098.167637427387;10966;10988.835167472334;11023.779524409307;11143.33140011602;11278.266912650979;9335.906640933776;9545.28410369239;9557.665481708107;9565.521723913917;9572.282078843975;6841.726330946905;6883.793486195422;6938.895916561357;6940.13248343957;6941.5140940135725;6929.896051559332;6966.1988871280455;7029.0862187915245;7046.295370462913;7116.822079447848 03:06:23 INFO - PID 3718 | |4;prototype.html;503.4009528660892;506.3291139240507;525.2882344423309;531.8193610185388;537.2639483907047;6133.693315334238;6233.37666233377;6283.685815709214;6283.717232724533;6311.35904664787;6115.48018418435;6173.9073913891325;6180.629162250257;6202.178009005903;6270.529710271737;4916.090523253191;4989.825356112537;5001.124803159432;5222.921656175142;5238.685678859271;4968.2795994580765;5018.197088465846;5031;5081.7459127043685;5088.313077734496;4999.925001124968;5023.246513023039;5047.419546752116;5051.318072060261;5058.165402708567;4984.252362145689;5019.57333626644;5048.469910659364;5071.416787069497;5073.797048118071;4545.659075569312;4556.111558246143;4556.681032327739;4560.931586026212;4586.72479651222;6059.333473317932;6075.33171351151;6166.876662466726;6187;6216.191895053659;5627.71861406928;5647.209390685307;5653.48419953121;5715.942260786113;5724.855028994176;6557.93442065578;6607.108040414579;6629.541500869801;6629.9005514916835;6639.73441062357;4875.171220892456;4879.4876537963355;4898.681585696954;4898.877528061791;4915.45929947709;6915.827104322382;6993.160820701498;7007;7027.683754231072;7033.226345102034;5008.899822003539;5044.470330615306;5068.391792984866;5080.161773307437;5103.948960510347;5103.18349064148;5113.488651134857;5131.871703207375;5135.12702840518;5142.922856157161;5464.863378415572;5652.8869422611315;5672.773089076473;5708.657480551097;5729.341125770507;6211.223597050369;6535.411812936859;6571.605703657796;6587.769428070091;6611.86776264472;1728.4814555633259;1730.3338214787398;1736.0364997426298;1762.770839790819;1765.3291749135246;1177.9116566257565;1196.1208511743862;1218.3847157185564;1239.1449899569268;1252.5741247975732;5553.194786755978;5684.289463817023;5701.8311246194435;5789;5819.563532735062;6723.5629684071355;6751.291114432914;6792.898106528406;6836.965815170893;6844.555103918229;5676.574256930747;5727.914081288784;5736.913946290809;5758.62568933018;5778.133280008033;3555.982220088883;3590.6588874056924;3598.676119149289;3635.8545658173907;3638.599754027055;6485.902711459332;6530.249021362604;6572.14562106923;6614;6621.304762999912;6877.1747390313485;7003.894941575881;7011.824704382431;7033.718651253893;7071.681774320168;6469.51478639104;6508.251551071688;6514.869702605826;6523.282438931714;6527.445167160749;6701.832454188684;6753.2233793112455;6792.554680315181;6800.285969973082;6810.761623343159;6625.4037136657935;6668.699908504032;6722.949500402424;6734.461243100638;6737.831554211184;6425.293217745951;6462.3537646235;6477.1579694639395;6531.9020214695865;6571.145751052524;4980.950190498049;5000.799968001239;5008.674436161784;5032.220005899091;5049.823256186016;6749.797506074826;6768.424683901923;6816.591004539743;6829.317068293131;6844.828879278058;6538.888388973791;6542.607443553402;6555.4427873631275;6561.573497722633;6563.212414510289;6484.675766211576;6492.935070649233;6511.544191906521;6576.539642224999;6588.901166482602;6459.386358295955;6504.6747662615735;6507;6525.347465253627;6591.505637077143;6172.845678858064;6338.968305158444;6437.259715132633;6596.921969579567;6612.891940787822;6262.34245404226;6278.4035516625845;6301.527385446018;6381.202349706286;6408.423241908251 03:06:23 INFO - PID 3718 | |5;yui.html;908.5502676175292;919.0258326174256;936.8173206224789;940.1820416237874;947.3321308477522;18910.716339254905;19281.4215573533;19762.703559446603;19778.208871645136;20356.69464958023;21618.351449456542;21883.343499694995;21932.8903355483;21939.890300548475;21974.670379944313;894.9418287811304;923.926085913127;924.8011677489338;938.4979036215626;975.4732444479972;989.3124278626358;992.4045572656421;992.7518120469882;992.8113658404916;994.636957510507;1037.2376303416982;1038.0916697889347;1041.1878734587035;1042.7601651620093;1049.727070961548;1026.984595231072;1031.7575369788094;1035.829088200446;1036.1555332404105;1043.9686809395691;760.1030783675261;766.3486036868672;766.5898744171841;768.4543973778624;769.0233403577481;18298.359557415428;18437.446876593724;18456.63086738271;18476.353327633467;18923.43229703111;21342.35972920815;21466.24868129608;21894.562108757895;21936.670949935764;21981.450463738376;17081.57296067596;17431.825681743274;17464;17473;17525;3674.356987527172;3704.4258139988347;3705.907352316187;3706.777593344408;3706.796126213057;134939;134996;136143.31928340296;137796;138671.99999999898;15664.216789160497;15925.601859953595;16051.197440127948;16079.196040197943;16129.274182661808;30276.84861575678;30702.69297307043;30812.537811932838;30836.84581577078;30892.69107308943;8794.692185773467;8820.558972051373;8854.911450885409;8913.28693704502;8965.103489650983;3695.3348397288746;3854.8265328060306;3904.7657140571655;4080.897977550585;4234.597713217239;5355.598330125256;5459;5464.043792336404;5494.2857428534035;5498.560115190775;1013.0730381700808;1039.0700323210683;1056.2025670618634;1057.2387880725864;1058.571278632155;662.6223052859824;671.9227288861746;681.3765404654785;685.8799710050721;690.0373978300344;10936.726581835517;11242.94378528102;11376.374299413612;11484.540618375171;11582.536698531965;674.780696273713;676.5669971218424;680.9761658342034;699.4124935054579;699.5872435263219;5631.380548139701;5664.150377443417;5665.575081868794;5723.141528770634;5818.94181058184;121630;123984;125141;128102;128601;111215.44392278149;111899;112700;114558;117685;91562.08437915535;92632;93562.53218733863;96611;96883;10438.321509101885;10648.467576620984;10657.14742820557;10806.891931080903;10838;43799.781001094794;44992;45073.32390014218;45353.546464534935;47688;58758.70620646869;59091.40908591031;59581.10628340665;60305.39694603173;61920;14895.702085958437;15251.313690883715;15413.306401211748;15432.537023889301;15576.065436073872;16062.759058614363;16248.837511624732;16343.182840858148;16474.505764827074;16501.752473713143;13519.864801351861;13733.793993089912;13750.862491374959;13780.586582402544;13801.861981380058;14916.477923272634;14978.325975330918;14987.550373488813;15203.163825989677;15208.315625797088;14511.782323264948;14682.853171468149;14781.630459238606;14944.701105978036;14985;10134.493275336352;10216.489175541044;10341.689749307532;10498.422586757802;10646.574137034431;11576.421178941187;11695.298282103102;11823.949642803549;12155.817662735242;12181.08641851847 03:06:23 INFO - PID 3718 | __end_tp_report 03:06:23 INFO - PID 3718 | __start_cc_report 03:06:23 INFO - PID 3718 | _x_x_mozilla_cycle_collect,384 03:06:23 INFO - PID 3718 | __end_cc_report 03:06:23 INFO - PID 3718 | __startTimestamp1504865183608__endTimestamp 03:06:23 INFO - PID 3718 | 03:06:23 INFO - PID 3718 | ------- Summary: start ------- 03:06:23 INFO - PID 3718 | Number of tests: 6 03:06:23 INFO - PID 3718 | 03:06:23 INFO - PID 3718 | [#0] dojo.html Cycles:165 Average:6209.68 Median:6445.90 stddev:762.25 (11.8%) stddev-sans-first:724.38 03:06:23 INFO - PID 3718 | Values: 3095.6 3110.5 3117.4 3122.6 3150.7 6170.4 6283.7 6362.0 6378.8 6395.8 6313.1 6350.0 6361.0 6375.2 6383.9 5832.5 5838.5 5880.3 5893.6 5927.6 5799.7 5802.1 5855.3 5873.2 5879.6 5553.5 5609.3 5639.9 5648.5 5802.9 5729.7 5737.5 5784.5 5794.6 5802.3 5674.1 5701.7 5750.0 5755.8 5768.7 6186.5 6262.0 6267.7 6293.0 6302.9 6700.5 6714.9 6752.4 6809.2 6837.8 6780.5 6825.9 6843.4 6917.1 6923.8 6347.2 6370.6 6386.6 6395.6 6403.5 6940.1 6956.8 7009.0 7037.1 7064.2 6418.6 6577.5 6645.2 6651.7 6741.3 6653.3 6666.1 6675.9 6677.7 6737.5 6714.5 6735.2 6789.0 6795.4 6808.8 6754.1 6754.7 6833.1 6842.2 6847.2 5171.1 5250.4 5272.9 5273.7 5324.8 4576.9 4609.1 4610.0 4687.2 4718.3 5763.1 5814.6 5829.3 5832.4 5835.9 6746.2 6767.6 6775.4 6860.6 6871.7 5634.5 5712.2 5733.6 5766.9 5813.9 5809.8 5846.2 5873.2 5878.7 5881.8 6418.2 6506.6 6509.2 6600.2 6600.7 6639.2 6740.8 6793.4 6878.8 6886.8 6383.5 6465.3 6479.1 6489.5 6503.1 6703.8 6706.2 6720.3 6768.6 6812.1 6639.8 6805.2 6833.4 6900.3 6927.3 6492.2 6502.7 6510.4 6520.0 6528.2 6462.1 6539.5 6543.2 6577.1 6589.0 6445.9 6538.3 6582.4 6609.7 6631.6 6592.2 6643.0 6706.2 6710.1 6725.7 6323.7 6355.5 6370.5 6445.5 6446.4 03:06:23 INFO - PID 3718 | 03:06:23 INFO - PID 3718 | [#1] ext.html Cycles:180 Average:37135.35 Median:37704.34 stddev:53200.72 (141.1%) stddev-sans-first:53280.06 03:06:23 INFO - PID 3718 | Values: 815.6 841.5 869.0 869.5 870.9 39728.4 40585.4 40690.0 40697.2 40704.6 37503.8 37810.2 37891.2 37893.6 37911.8 7046.7 7125.4 7133.1 7140.4 7148.9 7048.5 7109.5 7124.9 7126.4 7151.8 7104.6 7119.5 7128.2 7129.5 7137.1 7681.0 7915.7 7958.6 7964.5 7974.7 7735.0 7762.2 7763.8 7771.8 7781.9 37598.4 38238.0 38634.6 38855.8 38880.2 3449.1 3485.5 3486.3 3491.0 3500.2 32001.0 32053.4 32116.5 32179.0 32203.0 5139.8 5169.6 5170.7 5192.2 5236.7 179255.0 179702.0 179991.0 180193.0 181151.0 46843.0 46889.5 46891.8 46949.0 47011.0 48066.5 48587.5 48617.3 48666.5 48693.8 15897.0 16128.8 16131.5 16133.1 16142.0 13394.4 13457.7 13470.3 13470.9 13493.7 13661.4 13736.4 13736.9 13813.7 13822.6 5416.3 5449.5 5533.3 5536.8 5579.8 818.5 854.4 861.8 881.6 893.8 18674.4 18689.8 18737.3 18766.7 18906.7 956.1 967.6 998.8 999.4 1006.5 9908.3 9957.0 10072.3 10114.1 10200.6 221499.0 222404.0 222844.0 223037.0 223204.0 45455.1 46230.0 50618.0 56786.7 57202.0 1404.8 1440.8 1442.6 1456.9 1457.1 26814.9 27415.5 27486.9 27717.6 27735.3 38603.8 38630.8 38933.2 38976.0 38985.2 209173.0 210866.0 211140.0 211204.0 211395.0 33907.7 34948.5 35328.0 35337.8 35534.8 44752.8 44766.1 44983.6 45055.0 45292.5 31620.5 32082.8 32140.0 32295.1 32551.3 32029.4 32117.8 32136.5 32204.4 32441.8 35372.3 35760.3 36433.5 36604.6 36623.0 30606.8 30751.4 31082.2 31121.8 31364.7 22390.9 22733.9 22875.7 22921.3 23006.4 03:06:23 INFO - PID 3718 | 03:06:23 INFO - PID 3718 | [#2] jquery.html Cycles:180 Average:32410.04 Median:12289.79 stddev:72906.84 (593.2%) stddev-sans-first:73072.13 03:06:23 INFO - PID 3718 | Values: 553.3 556.4 562.5 564.0 566.8 21645.5 22400.9 22492.6 22514.5 22524.9 22749.4 24211.4 24589.4 24591.8 24598.3 6255.2 6371.7 6420.1 6421.2 6424.8 6294.0 6320.7 6346.4 6430.3 6431.5 6582.5 6583.5 6584.2 6585.9 6586.7 6592.9 6594.4 6595.4 6599.5 6599.8 5649.1 5931.6 5948.1 6099.8 6113.8 27850.7 28451.9 28491.1 28517.4 28602.3 2012.9 2031.1 2041.2 2044.0 2044.5 18481.9 19021.8 19101.8 19308.1 19309.6 2229.7 2276.6 2364.8 2365.0 2371.5 114011.0 119326.8 129320.4 132881.7 133638.3 14148.4 14301.2 14451.2 14530.3 14627.6 22093.6 22096.0 22120.2 22130.6 22218.1 9925.2 10110.3 10167.9 10276.4 10277.6 9530.1 9533.5 9539.6 9614.7 9621.0 8793.3 8975.3 9027.3 9034.1 9068.2 2634.8 2658.2 2670.1 2687.3 2771.7 931.6 960.6 962.1 967.4 974.9 18378.0 18601.3 18632.9 18922.5 19024.4 984.7 1005.8 1016.1 1022.8 1024.2 10205.3 10396.9 10404.1 10474.1 10627.2 420962.0 429198.0 431884.0 433163.0 436490.0 107199.5 109555.0 109653.0 113477.0 113650.0 1109.5 1141.6 1210.2 1226.3 1233.9 20459.3 20486.4 20604.9 20608.5 20819.4 56410.2 57720.7 57822.4 57953.0 57972.0 69666.7 70189.6 70462.6 70627.3 71123.3 14086.4 14452.9 14556.0 14576.6 14578.7 22173.0 22349.9 22677.7 22728.0 22766.9 17603.2 17906.2 17947.6 17999.8 18264.2 14646.2 14707.6 14992.6 15032.7 15931.8 18420.4 18426.2 18623.3 18698.7 18975.1 13378.9 14008.6 14103.4 14132.1 14145.2 17513.4 17770.5 17816.4 17843.0 18163.1 03:06:23 INFO - PID 3718 | 03:06:23 INFO - PID 3718 | [#3] mootools.html Cycles:180 Average:9292.16 Median:2709.65 stddev:6686.12 (246.8%) stddev-sans-first:6677.96 03:06:23 INFO - PID 3718 | Values: 1306.6 1389.5 1397.1 1404.6 1413.3 14838.3 15460.3 15486.1 15506.1 15509.1 15202.4 15281.6 15309.8 15349.5 15363.9 2648.1 2681.7 2729.7 2731.3 2737.2 2720.3 2739.5 2752.7 2757.1 2759.9 2656.5 2705.6 2847.9 2848.4 2848.6 2780.9 2794.7 2807.7 2816.7 2833.4 2576.6 2708.6 2710.7 2736.1 2755.9 15091.5 15145.7 15152.8 15242.3 15248.9 7878.7 8201.6 8204.0 8221.6 8226.8 5215.2 5433.8 5464.7 5515.9 5553.9 1950.2 1968.1 1978.9 2004.4 2016.4 26005.9 26169.9 26172.2 26238.7 26313.7 6647.3 6760.0 6802.2 7035.2 7040.8 16681.7 17065.7 17434.1 17481.0 17497.9 7746.6 7997.8 7999.7 8003.6 8004.7 7189.7 7309.1 7356.6 7359.2 7394.1 4947.9 5158.2 5160.8 5180.3 5199.2 1515.2 1516.8 1526.3 1541.7 1550.5 888.3 900.9 918.2 921.3 928.4 7551.2 7572.5 7601.0 7705.1 7714.9 663.7 681.7 682.5 706.9 708.0 4221.0 4853.8 5211.3 5234.0 5380.4 21187.7 21459.7 21656.6 21985.9 22051.8 21307.1 21431.9 21615.7 21769.2 21819.0 11473.2 11493.9 11523.0 11577.2 11660.8 8254.7 8280.6 8301.3 8387.6 8389.3 18410.6 18942.0 19008.8 19105.0 19367.2 18624.0 18990.0 19327.5 19494.3 19683.8 10935.4 11027.1 11037.6 11105.4 11122.6 13650.7 13788.1 13792.6 13793.7 13849.1 10767.6 10792.5 10878.8 11088.4 11098.2 10966.0 10988.8 11023.8 11143.3 11278.3 9335.9 9545.3 9557.7 9565.5 9572.3 6841.7 6883.8 6938.9 6940.1 6941.5 6929.9 6966.2 7029.1 7046.3 7116.8 03:06:23 INFO - PID 3718 | 03:06:23 INFO - PID 3718 | [#4] prototype.html Cycles:180 Average:5538.08 Median:6173.38 stddev:1551.62 (25.1%) stddev-sans-first:1509.26 03:06:23 INFO - PID 3718 | Values: 503.4 506.3 525.3 531.8 537.3 6133.7 6233.4 6283.7 6283.7 6311.4 6115.5 6173.9 6180.6 6202.2 6270.5 4916.1 4989.8 5001.1 5222.9 5238.7 4968.3 5018.2 5031.0 5081.7 5088.3 4999.9 5023.2 5047.4 5051.3 5058.2 4984.3 5019.6 5048.5 5071.4 5073.8 4545.7 4556.1 4556.7 4560.9 4586.7 6059.3 6075.3 6166.9 6187.0 6216.2 5627.7 5647.2 5653.5 5715.9 5724.9 6557.9 6607.1 6629.5 6629.9 6639.7 4875.2 4879.5 4898.7 4898.9 4915.5 6915.8 6993.2 7007.0 7027.7 7033.2 5008.9 5044.5 5068.4 5080.2 5103.9 5103.2 5113.5 5131.9 5135.1 5142.9 5464.9 5652.9 5672.8 5708.7 5729.3 6211.2 6535.4 6571.6 6587.8 6611.9 1728.5 1730.3 1736.0 1762.8 1765.3 1177.9 1196.1 1218.4 1239.1 1252.6 5553.2 5684.3 5701.8 5789.0 5819.6 6723.6 6751.3 6792.9 6837.0 6844.6 5676.6 5727.9 5736.9 5758.6 5778.1 3556.0 3590.7 3598.7 3635.9 3638.6 6485.9 6530.2 6572.1 6614.0 6621.3 6877.2 7003.9 7011.8 7033.7 7071.7 6469.5 6508.3 6514.9 6523.3 6527.4 6701.8 6753.2 6792.6 6800.3 6810.8 6625.4 6668.7 6722.9 6734.5 6737.8 6425.3 6462.4 6477.2 6531.9 6571.1 4981.0 5000.8 5008.7 5032.2 5049.8 6749.8 6768.4 6816.6 6829.3 6844.8 6538.9 6542.6 6555.4 6561.6 6563.2 6484.7 6492.9 6511.5 6576.5 6588.9 6459.4 6504.7 6507.0 6525.3 6591.5 6172.8 6339.0 6437.3 6596.9 6612.9 6262.3 6278.4 6301.5 6381.2 6408.4 03:06:23 INFO - PID 3718 | 03:06:23 INFO - PID 3718 | [#5] yui.html Cycles:180 Average:24339.27 Median:19102.43 stddev:35651.45 (186.6%) stddev-sans-first:35708.05 03:06:23 INFO - PID 3718 | Values: 908.6 919.0 936.8 940.2 947.3 18910.7 19281.4 19762.7 19778.2 20356.7 21618.4 21883.3 21932.9 21939.9 21974.7 894.9 923.9 924.8 938.5 975.5 989.3 992.4 992.8 992.8 994.6 1037.2 1038.1 1041.2 1042.8 1049.7 1027.0 1031.8 1035.8 1036.2 1044.0 760.1 766.3 766.6 768.5 769.0 18298.4 18437.4 18456.6 18476.4 18923.4 21342.4 21466.2 21894.6 21936.7 21981.5 17081.6 17431.8 17464.0 17473.0 17525.0 3674.4 3704.4 3705.9 3706.8 3706.8 134939.0 134996.0 136143.3 137796.0 138672.0 15664.2 15925.6 16051.2 16079.2 16129.3 30276.8 30702.7 30812.5 30836.8 30892.7 8794.7 8820.6 8854.9 8913.3 8965.1 3695.3 3854.8 3904.8 4080.9 4234.6 5355.6 5459.0 5464.0 5494.3 5498.6 1013.1 1039.1 1056.2 1057.2 1058.6 662.6 671.9 681.4 685.9 690.0 10936.7 11242.9 11376.4 11484.5 11582.5 674.8 676.6 681.0 699.4 699.6 5631.4 5664.2 5665.6 5723.1 5818.9 121630.0 123984.0 125141.0 128102.0 128601.0 111215.4 111899.0 112700.0 114558.0 117685.0 91562.1 92632.0 93562.5 96611.0 96883.0 10438.3 10648.5 10657.1 10806.9 10838.0 43799.8 44992.0 45073.3 45353.5 47688.0 58758.7 59091.4 59581.1 60305.4 61920.0 14895.7 15251.3 15413.3 15432.5 15576.1 16062.8 16248.8 16343.2 16474.5 16501.8 13519.9 13733.8 13750.9 13780.6 13801.9 14916.5 14978.3 14987.6 15203.2 15208.3 14511.8 14682.9 14781.6 14944.7 14985.0 10134.5 10216.5 10341.7 10498.4 10646.6 11576.4 11695.3 11823.9 12155.8 12181.1 03:06:23 INFO - PID 3718 | -------- Summary: end -------- 03:06:23 INFO - PID 3718 | 03:06:24 INFO - TEST-INFO | 3718: exit 0 03:06:24 INFO - TEST-OK | dromaeo_css | took 1110633ms 03:06:24 INFO - TEST-START | kraken 03:06:24 INFO - Initialising browser for kraken test... 03:06:24 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:35266/getInfo.html -profile /tmp/tmpboSpjQ/profile 03:06:24 INFO - TEST-INFO | started process 4091 (/builds/slave/test/build/application/firefox/firefox http://localhost:35266/getInfo.html) 03:06:30 INFO - TEST-INFO | 4091: exit 0 03:06:30 INFO - Browser initialized. 03:06:30 INFO - Running cycle 1/1 for kraken test... 03:06:30 INFO - Using env: {'DISPLAY': ':0', 03:06:30 INFO - 'HOME': '/home/cltbld', 03:06:30 INFO - 'JSGC_DISABLE_POISONING': '1', 03:06:30 INFO - 'LANG': 'en_US.UTF-8', 03:06:30 INFO - 'LANGUAGE': 'en_US:en', 03:06:30 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:06:30 INFO - 'LOGNAME': 'cltbld', 03:06:30 INFO - 'MAIL': '/var/mail/cltbld', 03:06:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:06:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:06:30 INFO - 'MOZ_CRASHREPORTER': '1', 03:06:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:06:30 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:06:30 INFO - 'MOZ_NO_REMOTE': '1', 03:06:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:06:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:06:30 INFO - 'NO_EM_RESTART': '1', 03:06:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:06:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:06:30 INFO - 'PWD': '/builds/slave/test', 03:06:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:06:30 INFO - 'RUST_BACKTRACE': 'full', 03:06:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:06:30 INFO - 'SHELL': '/bin/bash', 03:06:30 INFO - 'SHLVL': '1', 03:06:30 INFO - 'STYLO_FORCE_ENABLED': '1', 03:06:30 INFO - 'STYLO_THREADS': '1', 03:06:30 INFO - 'TERM': 'linux', 03:06:30 INFO - 'TMOUT': '86400', 03:06:30 INFO - 'USER': 'cltbld', 03:06:30 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411', 03:06:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:06:30 INFO - '_': '/tools/buildbot/bin/python'} 03:06:30 INFO - TEST-INFO | started process 4277 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpboSpjQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:06:31 INFO - PID 4277 | 03:06:31 INFO - PID 4277 | (/builds/slave/test/build/application/firefox/firefox:4327): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:06:31 INFO - PID 4277 | 03:06:42 INFO - PID 4277 | RSS: Main: 166772736 03:06:42 INFO - PID 4277 | 03:06:44 INFO - PID 4277 | Cycle 1(1): loaded http://localhost:35266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35266/tests/kraken/driver.html?testName=audio-beat-detection) 03:06:45 INFO - PID 4277 | RSS: Main: 172228608 03:06:45 INFO - PID 4277 | 03:06:48 INFO - PID 4277 | Cycle 1(1): loaded http://localhost:35266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35266/tests/kraken/driver.html?testName=audio-dft) 03:06:48 INFO - PID 4277 | RSS: Main: 173953024 03:06:48 INFO - PID 4277 | 03:06:51 INFO - PID 4277 | Cycle 1(1): loaded http://localhost:35266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35266/tests/kraken/driver.html?testName=audio-fft) 03:06:52 INFO - PID 4277 | RSS: Main: 167882752 03:06:52 INFO - PID 4277 | 03:06:54 INFO - PID 4277 | Cycle 1(1): loaded http://localhost:35266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35266/tests/kraken/driver.html?testName=audio-oscillator) 03:06:55 INFO - PID 4277 | RSS: Main: 169426944 03:06:55 INFO - PID 4277 | 03:06:57 INFO - PID 4277 | Cycle 1(1): loaded http://localhost:35266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:06:57 INFO - PID 4277 | RSS: Main: 168763392 03:06:57 INFO - PID 4277 | 03:07:01 INFO - PID 4277 | Cycle 1(1): loaded http://localhost:35266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35266/tests/kraken/driver.html?testName=imaging-darkroom) 03:07:01 INFO - PID 4277 | RSS: Main: 171835392 03:07:01 INFO - PID 4277 | 03:07:05 INFO - PID 4277 | Cycle 1(1): loaded http://localhost:35266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35266/tests/kraken/driver.html?testName=imaging-desaturate) 03:07:05 INFO - PID 4277 | RSS: Main: 171446272 03:07:05 INFO - PID 4277 | 03:07:08 INFO - PID 4277 | Cycle 1(1): loaded http://localhost:35266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35266/tests/kraken/driver.html?testName=json-parse-financial) 03:07:09 INFO - PID 4277 | RSS: Main: 171941888 03:07:09 INFO - PID 4277 | 03:07:11 INFO - PID 4277 | Cycle 1(1): loaded http://localhost:35266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:07:11 INFO - PID 4277 | RSS: Main: 171495424 03:07:11 INFO - PID 4277 | 03:07:13 INFO - PID 4277 | Cycle 1(1): loaded http://localhost:35266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35266/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:07:13 INFO - PID 4277 | RSS: Main: 171737088 03:07:13 INFO - PID 4277 | 03:07:16 INFO - PID 4277 | Cycle 1(1): loaded http://localhost:35266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:07:17 INFO - PID 4277 | RSS: Main: 172593152 03:07:17 INFO - PID 4277 | 03:07:19 INFO - PID 4277 | Cycle 1(1): loaded http://localhost:35266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:07:20 INFO - PID 4277 | RSS: Main: 173006848 03:07:20 INFO - PID 4277 | 03:07:23 INFO - PID 4277 | Cycle 1(1): loaded http://localhost:35266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:07:24 INFO - PID 4277 | RSS: Main: 172945408 03:07:24 INFO - PID 4277 | 03:07:25 INFO - PID 4277 | Cycle 1(1): loaded http://localhost:35266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35266/tests/kraken/driver.html?testName=ai-astar) 03:07:26 INFO - PID 4277 | RSS: Main: 173871104 03:07:26 INFO - PID 4277 | 03:07:26 INFO - PID 4277 | __start_tp_report 03:07:26 INFO - PID 4277 | _x_x_mozilla_page_load 03:07:26 INFO - PID 4277 | _x_x_mozilla_page_load_details 03:07:26 INFO - PID 4277 | |i|pagename|runs| 03:07:26 INFO - PID 4277 | |0;ai-astar;107;106;107;106;107;100;109;107;107;108 03:07:26 INFO - PID 4277 | |1;audio-beat-detection;119;120;116;119;125;115;120;117;118;116 03:07:26 INFO - PID 4277 | |2;audio-dft;164;161;162;162;160;162;165;170;167;163 03:07:26 INFO - PID 4277 | |3;audio-fft;73;77;81;75;73;73;74;75;75;75 03:07:26 INFO - PID 4277 | |4;audio-oscillator;80;78;80;78;82;78;79;79;78;79 03:07:26 INFO - PID 4277 | |5;imaging-gaussian-blur;113;114;114;114;114;116;114;111;114;115 03:07:26 INFO - PID 4277 | |6;imaging-darkroom;150;148;151;150;148;150;149;148;150;148 03:07:26 INFO - PID 4277 | |7;imaging-desaturate;94;94;95;94;95;94;94;95;94;95 03:07:26 INFO - PID 4277 | |8;json-parse-financial;52;53;53;54;53;54;52;52;53;51 03:07:26 INFO - PID 4277 | |9;json-stringify-tinderbox;37;37;38;37;38;38;38;38;38;38 03:07:26 INFO - PID 4277 | |10;stanford-crypto-aes;77;74;74;78;80;77;74;80;74;75 03:07:26 INFO - PID 4277 | |11;stanford-crypto-ccm;120;116;123;116;118;116;120;122;133;125 03:07:26 INFO - PID 4277 | |12;stanford-crypto-pbkdf2;169;170;168;168;180;165;167;171;170;167 03:07:26 INFO - PID 4277 | |13;stanford-crypto-sha256-iterative;66;62;63;64;63;63;64;61;72;65 03:07:26 INFO - PID 4277 | __end_tp_report 03:07:26 INFO - PID 4277 | __start_cc_report 03:07:26 INFO - PID 4277 | _x_x_mozilla_cycle_collect,947 03:07:26 INFO - PID 4277 | __end_cc_report 03:07:26 INFO - PID 4277 | __startTimestamp1504865246201__endTimestamp 03:07:26 INFO - PID 4277 | 03:07:26 INFO - PID 4277 | ------- Summary: start ------- 03:07:26 INFO - PID 4277 | Number of tests: 14 03:07:26 INFO - PID 4277 | 03:07:26 INFO - PID 4277 | [#0] ai-astar Cycles:10 Average:106.40 Median:107.00 stddev:2.41 (2.3%) stddev-sans-first:2.55 03:07:26 INFO - PID 4277 | Values: 107.0 106.0 107.0 106.0 107.0 100.0 109.0 107.0 107.0 108.0 03:07:26 INFO - PID 4277 | 03:07:26 INFO - PID 4277 | [#1] audio-beat-detection Cycles:10 Average:118.50 Median:119.00 stddev:2.88 (2.4%) stddev-sans-first:3.05 03:07:26 INFO - PID 4277 | Values: 119.0 120.0 116.0 119.0 125.0 115.0 120.0 117.0 118.0 116.0 03:07:26 INFO - PID 4277 | 03:07:26 INFO - PID 4277 | [#2] audio-dft Cycles:10 Average:163.60 Median:163.50 stddev:3.03 (1.9%) stddev-sans-first:3.21 03:07:26 INFO - PID 4277 | Values: 164.0 161.0 162.0 162.0 160.0 162.0 165.0 170.0 167.0 163.0 03:07:26 INFO - PID 4277 | 03:07:26 INFO - PID 4277 | [#3] audio-fft Cycles:10 Average:75.10 Median:75.00 stddev:2.42 (3.2%) stddev-sans-first:2.45 03:07:26 INFO - PID 4277 | Values: 73.0 77.0 81.0 75.0 73.0 73.0 74.0 75.0 75.0 75.0 03:07:26 INFO - PID 4277 | 03:07:26 INFO - PID 4277 | [#4] audio-oscillator Cycles:10 Average:79.10 Median:79.00 stddev:1.29 (1.6%) stddev-sans-first:1.32 03:07:26 INFO - PID 4277 | Values: 80.0 78.0 80.0 78.0 82.0 78.0 79.0 79.0 78.0 79.0 03:07:26 INFO - PID 4277 | 03:07:26 INFO - PID 4277 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:1.29 (1.1%) stddev-sans-first:1.32 03:07:26 INFO - PID 4277 | Values: 113.0 114.0 114.0 114.0 114.0 116.0 114.0 111.0 114.0 115.0 03:07:26 INFO - PID 4277 | 03:07:26 INFO - PID 4277 | [#6] imaging-darkroom Cycles:10 Average:149.20 Median:150.00 stddev:1.14 (0.8%) stddev-sans-first:1.17 03:07:26 INFO - PID 4277 | Values: 150.0 148.0 151.0 150.0 148.0 150.0 149.0 148.0 150.0 148.0 03:07:26 INFO - PID 4277 | 03:07:26 INFO - PID 4277 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 03:07:26 INFO - PID 4277 | Values: 94.0 94.0 95.0 94.0 95.0 94.0 94.0 95.0 94.0 95.0 03:07:26 INFO - PID 4277 | 03:07:26 INFO - PID 4277 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:53.00 stddev:0.95 (1.8%) stddev-sans-first:0.97 03:07:26 INFO - PID 4277 | Values: 52.0 53.0 53.0 54.0 53.0 54.0 52.0 52.0 53.0 51.0 03:07:26 INFO - PID 4277 | 03:07:26 INFO - PID 4277 | [#9] json-stringify-tinderbox Cycles:10 Average:37.70 Median:38.00 stddev:0.48 (1.3%) stddev-sans-first:0.44 03:07:26 INFO - PID 4277 | Values: 37.0 37.0 38.0 37.0 38.0 38.0 38.0 38.0 38.0 38.0 03:07:26 INFO - PID 4277 | 03:07:26 INFO - PID 4277 | [#10] stanford-crypto-aes Cycles:10 Average:76.30 Median:77.00 stddev:2.45 (3.2%) stddev-sans-first:2.59 03:07:26 INFO - PID 4277 | Values: 77.0 74.0 74.0 78.0 80.0 77.0 74.0 80.0 74.0 75.0 03:07:26 INFO - PID 4277 | 03:07:26 INFO - PID 4277 | [#11] stanford-crypto-ccm Cycles:10 Average:120.90 Median:121.00 stddev:5.28 (4.4%) stddev-sans-first:5.59 03:07:26 INFO - PID 4277 | Values: 120.0 116.0 123.0 116.0 118.0 116.0 120.0 122.0 133.0 125.0 03:07:26 INFO - PID 4277 | 03:07:26 INFO - PID 4277 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.50 Median:169.50 stddev:4.09 (2.4%) stddev-sans-first:4.33 03:07:26 INFO - PID 4277 | Values: 169.0 170.0 168.0 168.0 180.0 165.0 167.0 171.0 170.0 167.0 03:07:26 INFO - PID 4277 | 03:07:26 INFO - PID 4277 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.30 Median:64.00 stddev:3.06 (4.8%) stddev-sans-first:3.18 03:07:26 INFO - PID 4277 | Values: 66.0 62.0 63.0 64.0 63.0 63.0 64.0 61.0 72.0 65.0 03:07:26 INFO - PID 4277 | -------- Summary: end -------- 03:07:26 INFO - PID 4277 | 03:07:26 INFO - PID 4277 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:07:26 INFO - TEST-INFO | 4277: exit 0 03:07:26 INFO - TEST-OK | kraken | took 62622ms 03:07:26 INFO - SUITE-END | took 1173s 03:07:27 INFO - Completed test suite (00:19:33) 03:07:27 INFO - javascript benchmark 03:07:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8419.392275688517, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3095.5666206731053, 3110.4712198926186, 3117.4388610050196, 3122.625284965803, 3150.747940164787, 6170.352113028123, 6283.7486500540035, 6362.0, 6378.776742814002, 6395.776147834827, 6313.084602732601, 6349.968250158754, 6361.0, 6375.203099612549, 6383.872322553558, 5832.475077243047, 5838.474537291643, 5880.323762767293, 5893.6463812171405, 5927.644341339534, 5799.681017544033, 5802.100674395476, 5855.326637436686, 5873.207117039187, 5879.559033072515, 5553.527950124204, 5609.3268807743125, 5639.8590035249035, 5648.491635752802, 5802.854928626797, 5729.6562206267345, 5737.540996720252, 5784.508316793035, 5794.623349482313, 5802.274715660543, 5674.063779476382, 5701.657900525982, 5749.971250143764, 5755.827325180251, 5768.653880767168, 6186.536009799238, 6262.0, 6267.71795269214, 6292.968535157295, 6302.87394252117, 6700.463962882957, 6714.932850671479, 6752.358525940028, 6809.182898052265, 6837.829054273633, 6780.457563394916, 6825.897611535831, 6843.3840954314355, 6917.13535808024, 6923.7576684816295, 6347.174867267272, 6370.649614271213, 6386.584871983307, 6395.584287021329, 6403.48772098231, 6940.09778728762, 6956.79129626112, 7008.964955175294, 7037.120359955005, 7064.222935477093, 6418.6469744164515, 6577.473802095917, 6645.169353830771, 6651.700673469705, 6741.258461569223, 6653.301403352675, 6666.133402657623, 6675.899861502081, 6677.699503522354, 6737.461003119739, 6714.462842972551, 6735.225449073321, 6789.049533065375, 6795.388415042571, 6808.795736127924, 6754.088198093293, 6754.662266886636, 6833.077534532775, 6842.178938527409, 6847.246802851682, 5171.146760784503, 5250.448702886142, 5272.868178295496, 5273.683578985273, 5324.760385782649, 4576.908461830745, 4609.055143695535, 4609.953900460952, 4687.179743544866, 4718.292256161534, 5763.135529670583, 5814.592978491465, 5829.300483941955, 5832.416758324219, 5835.941640583625, 6746.156730408699, 6767.627780472121, 6775.390214880685, 6860.588364698134, 6871.690773915186, 5634.549236061105, 5712.171735098471, 5733.627314224562, 5766.94233057664, 5813.941860581424, 5809.767609295665, 5846.181534585162, 5873.236479257669, 5878.735456904449, 5881.7647294107755, 6418.229812422446, 6506.642134682638, 6509.153810004669, 6600.207975043026, 6600.702968366435, 6639.170103737033, 6740.764073257413, 6793.354631310018, 6878.759243426456, 6886.758963436256, 6383.489320854414, 6465.256495502889, 6479.060536222127, 6489.480841532761, 6503.122078519339, 6703.826830304774, 6706.161729783777, 6720.260771315249, 6768.627725475146, 6812.080369150101, 6639.767608133692, 6805.217399999064, 6833.384995350442, 6900.30996900326, 6927.27263637331, 6492.188476440445, 6502.707378168088, 6510.44661203803, 6519.967400162969, 6528.151340325917, 6462.095306657166, 6539.509536784665, 6543.1821022372205, 6577.079208910661, 6589.0, 6445.903311450238, 6538.346165383614, 6582.407583317525, 6609.669516524058, 6631.568948018364, 6592.208934927839, 6643.0, 6706.195256569243, 6710.094137291403, 6725.73097076131, 6323.715432805443, 6355.523335749745, 6370.490360771035, 6445.452136568444, 6446.387593178641], "value": 6150.0398768062, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [815.5636734347122, 841.495102938237, 869.0266901070802, 869.5043825019737, 870.8824308718321, 39728.40407393892, 40585.3912191317, 40690.0, 40697.18605627893, 40704.59295407049, 37503.81248093756, 37810.24379512408, 37891.24217515655, 37893.62106378935, 37911.810440947826, 7046.7181312747425, 7125.39434148098, 7133.144022717255, 7140.39306658932, 7148.928510714904, 7048.5418447800985, 7109.537880037807, 7124.893126603105, 7126.358627723504, 7151.821204469878, 7104.644767761617, 7119.466040046991, 7128.215896251409, 7129.5365801223015, 7137.072180616513, 7680.961595192016, 7915.722949696762, 7958.562279074648, 7964.482308649977, 7974.720884769005, 7735.0, 7762.223777622249, 7763.767086987399, 7771.805704857367, 7781.922180778176, 37598.43602345967, 38238.0, 38634.61365386338, 38855.8057209715, 38880.22239555221, 3449.137715571107, 3485.5468789057513, 3486.337595856779, 3491.039964009892, 3500.177458297298, 32001.0, 32053.358932821448, 32116.518252226237, 32179.0, 32203.0, 5139.820106296262, 5169.638125331228, 5170.741462926839, 5192.1952097424955, 5236.7119808410525, 179255.0, 179702.0, 179991.0, 180192.9999999987, 181151.0, 46843.0, 46889.53110468852, 46891.76554117208, 46949.0, 47011.0, 48066.5193348062, 48587.51412485901, 48617.27074093891, 48666.513334866904, 48693.75653121783, 15897.046177229404, 16128.838711612734, 16131.516054518383, 16133.112678802543, 16142.03147811135, 13394.397252123677, 13457.730845383037, 13470.326483675777, 13470.865291346961, 13493.66265843342, 13661.38523766413, 13736.381862816197, 13736.93131534336, 13813.723725525433, 13822.585322440344, 5416.2688037114485, 5449.536789372868, 5533.280673512418, 5536.7785288588, 5579.776808927598, 818.4516374029415, 854.410456784829, 861.8104017116225, 881.5944665453964, 893.7944272817289, 18674.439766807016, 18689.81310186908, 18737.250509979447, 18766.718499222523, 18906.716399254023, 956.0726095687172, 967.5645959318335, 998.8301988661945, 999.3853779925294, 1006.5369929832212, 9908.306418550632, 9957.00429957013, 10072.345297555636, 10114.089731924016, 10200.591976320864, 221499.0, 222404.0, 222844.0, 223037.0, 223204.0, 45455.09089818185, 46230.0, 50618.0, 56786.71606642023, 57202.0, 1404.817373741407, 1440.819897512811, 1442.5600191941558, 1456.941722331095, 1457.1038811131077, 26814.86592567025, 27415.451690966467, 27486.862565687043, 27717.584236236875, 27735.30661733392, 38603.80698096604, 38630.80684596559, 38933.2213355737, 38976.0, 38985.22029559336, 209173.0, 210866.0, 211140.0, 211204.0, 211395.0, 33907.66092339045, 34948.47577286394, 35328.0, 35337.82331088328, 35534.822325888206, 44752.77623611861, 44766.104677905605, 44983.55016449794, 45055.0, 45292.54707452883, 31620.525692114177, 32082.83958580192, 32140.0, 32295.08605016083, 32551.348973019933, 32029.35941281208, 32117.839410802797, 32136.517952231203, 32204.355912882078, 32441.837790810896, 35372.29255414929, 35760.28479430449, 36433.45349819702, 36604.63395366012, 36623.0, 30606.846965765028, 30751.384972300875, 31082.22294442657, 31121.8443907779, 31364.686353136178, 22390.888045559666, 22733.886330568243, 22875.656865146702, 22921.312360629207, 23006.42483937915], "value": 16710.610061375915, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.2586334312022, 556.3657430529197, 562.5471495446166, 563.963342382745, 566.8398677373642, 21645.45886352842, 22400.88799556002, 22492.55014899703, 22514.549709005852, 22524.88737556314, 22749.4312642184, 24211.39471513213, 24589.38526536833, 24591.75408245917, 24598.26205213842, 6255.155554000208, 6371.713272902707, 6420.133282006939, 6421.165248517709, 6424.807255782334, 6294.0, 6320.74717011319, 6346.365363463639, 6430.260520040209, 6431.453326467233, 6582.539222254444, 6583.5062370322175, 6584.176977877765, 6585.934140658604, 6586.670666466657, 6592.9340706593275, 6594.373534514213, 6595.406413422791, 6599.505037122235, 6599.769008084742, 5649.12438572022, 5931.555133365015, 5948.078047902582, 6099.756009759604, 6113.84715382119, 27850.721492785015, 28451.857740711162, 28491.145265642062, 28517.429651406856, 28602.284942876388, 2012.8691635043822, 2031.0555591649954, 2041.173324803442, 2044.0188709419563, 2044.4582185720797, 18481.907590462095, 19021.809781902142, 19101.80898191014, 19308.131134098858, 19309.61380772391, 2229.6989906362596, 2276.612975794119, 2364.7517010713973, 2364.97635023651, 2371.537550177716, 114011.0, 119326.80673193243, 129320.35339823335, 132881.6711832879, 133638.33180834033, 14148.434062637585, 14301.213433261062, 14451.242303228168, 14530.346134423977, 14627.561173164822, 22093.558128837332, 22096.0, 22120.2257920972, 22130.557388852132, 22218.11127554912, 9925.156361709336, 10110.342827716322, 10167.898321016843, 10276.434796086138, 10277.640282590073, 9530.142287194185, 9533.52332383378, 9539.61841526345, 9614.71155865325, 9620.95189524048, 8793.296536277083, 8975.326850486239, 9027.322950778718, 9034.14175653312, 9068.183863452321, 2634.7628713415884, 2658.1626787561854, 2670.092168662625, 2687.341601307653, 2771.7228277172526, 931.6459745296744, 960.5677445149711, 962.1148543340144, 967.4388854464394, 974.878140232471, 18378.0, 18601.34895278686, 18632.90683546601, 18922.526936826413, 19024.42926712201, 984.6849008317413, 1005.7938122684832, 1016.1007508355159, 1022.8210063238903, 1024.156518267116, 10205.285630005978, 10396.948015260026, 10404.11565016982, 10474.109700675375, 10627.202959778047, 420962.0, 429198.0, 431884.0, 433163.0, 436490.0, 107199.46400268105, 109555.0, 109653.0, 113477.0, 113650.0, 1109.5395410904546, 1141.6357452843772, 1210.2194084815244, 1226.343906010298, 1233.8827811358087, 20459.28392506255, 20486.38540843777, 20604.896975515025, 20608.48478788042, 20819.37541873746, 56410.153847691494, 57720.71139644275, 57822.4217757817, 57953.0, 57972.0, 69666.65166674134, 70189.64905175442, 70462.64768676124, 70627.29372706208, 71123.28876711166, 14086.36611352512, 14452.855471445151, 14556.0, 14576.635584110481, 14578.708425831635, 22173.0, 22349.888250558644, 22677.659835102157, 22728.0, 22766.886165569067, 17603.207855646782, 17906.194221259815, 17947.641047179244, 17999.820001800337, 18264.178111984726, 14646.194459304841, 14707.55877323682, 14992.550223493312, 15032.699346013238, 15931.840681593036, 18420.35528756487, 18426.170822313296, 18623.255069797055, 18698.719519206952, 18975.14611842498, 13378.93310533441, 14008.649783755487, 14103.435862565382, 14132.081414708011, 14145.222012789396, 17513.38703145435, 17770.466885993443, 17816.376426824998, 17843.0, 18163.091845407413], "value": 11691.48192811264, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1306.581893793986, 1389.5067251125847, 1397.1477398786737, 1404.6137312239139, 1413.2651021468837, 14838.332275047622, 15460.304286307115, 15486.148261845594, 15506.14716190609, 15509.146996915179, 15202.391904323842, 15281.617959551017, 15309.846901530982, 15349.462768803094, 15363.92318038411, 2648.0731743889583, 2681.691605465372, 2729.727027297274, 2731.2762118038736, 2737.178846346098, 2720.279126031603, 2739.4521095780824, 2752.6972033076445, 2757.131503576367, 2759.903403380882, 2656.4687062587464, 2705.580635001568, 2847.857607119646, 2848.387596666716, 2848.6154369160104, 2780.8748606312765, 2794.748472637452, 2807.719228077186, 2816.7324104210065, 2833.3766571354267, 2576.600626902833, 2708.5801700736415, 2710.715374885648, 2736.1244401791437, 2755.9448811023667, 15091.54725358241, 15145.697086058328, 15152.772708409273, 15242.314095865713, 15248.923755381264, 7878.724244651467, 8201.589920503951, 8204.0, 8221.588920553948, 8226.835463290701, 5215.24378965048, 5433.809816656438, 5464.672119672832, 5515.944840551583, 5553.944460555424, 1950.1906708716008, 1968.1340210307562, 1978.9208431662717, 2004.4487765864358, 2016.3648450737971, 26005.869970650216, 26169.869150654315, 26172.214833555023, 26238.737612624012, 26313.736862631322, 6647.268800431949, 6760.0, 6802.163415877685, 7035.155781306276, 7040.823979400454, 16681.749773753403, 17065.7440138398, 17434.12829358527, 17481.03854288001, 17497.91251043762, 7746.612669366622, 7997.800054998555, 7999.720009799746, 8003.559804210708, 8004.679812807539, 7189.676464559107, 7309.086364204474, 7356.558606483521, 7359.153697324876, 7394.075740532434, 4947.925781113287, 5158.246196293203, 5160.819371321985, 5180.300659410931, 5199.220116982483, 1515.2272341106116, 1516.7724841273678, 1526.3207872496855, 1541.714782765192, 1550.5115888495088, 888.3337496877342, 900.9234215091659, 918.2332752151895, 921.3412410126791, 928.4429342394509, 7551.169371369255, 7572.507786993828, 7600.961995189989, 7705.113911900091, 7714.9228507715325, 663.6847497438774, 681.6966449929832, 682.5188242289174, 706.908101946754, 708.0193931404938, 4221.0, 4853.830115945995, 5211.270422140903, 5233.973830130901, 5380.381256155514, 21187.68218476724, 21459.67810482844, 21656.56686866254, 21985.890070549543, 22051.779482204973, 21307.14771409126, 21431.89284053601, 21615.67576486354, 21769.238076667556, 21819.0, 11473.196876218752, 11493.885061149282, 11523.0, 11577.247478913843, 11660.825087623693, 8254.669813207645, 8280.627371768165, 8301.335893128657, 8387.580620968805, 8389.286910612665, 18410.631787364444, 18942.0, 19008.809911900706, 19105.0, 19367.225310987404, 18624.0, 18990.0, 19327.51681207981, 19494.31769888047, 19683.803161968197, 10935.398553079656, 11027.117830573696, 11037.613683521038, 11105.444727763741, 11122.555097796321, 13650.726985460036, 13788.103773254707, 13792.586222413343, 13793.724125517634, 13849.099808512414, 10767.6231331903, 10792.514336854703, 10878.836817447585, 11088.445577721242, 11098.167637427387, 10966.0, 10988.835167472334, 11023.779524409307, 11143.33140011602, 11278.266912650979, 9335.906640933776, 9545.28410369239, 9557.665481708107, 9565.521723913917, 9572.282078843975, 6841.726330946905, 6883.793486195422, 6938.895916561357, 6940.13248343957, 6941.5140940135725, 6929.896051559332, 6966.1988871280455, 7029.0862187915245, 7046.295370462913, 7116.822079447848], "value": 6600.172745067791, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.4009528660892, 506.3291139240507, 525.2882344423309, 531.8193610185388, 537.2639483907047, 6133.693315334238, 6233.37666233377, 6283.685815709214, 6283.717232724533, 6311.35904664787, 6115.48018418435, 6173.9073913891325, 6180.629162250257, 6202.178009005903, 6270.529710271737, 4916.090523253191, 4989.825356112537, 5001.124803159432, 5222.921656175142, 5238.685678859271, 4968.2795994580765, 5018.197088465846, 5031.0, 5081.7459127043685, 5088.313077734496, 4999.925001124968, 5023.246513023039, 5047.419546752116, 5051.318072060261, 5058.165402708567, 4984.252362145689, 5019.57333626644, 5048.469910659364, 5071.416787069497, 5073.797048118071, 4545.659075569312, 4556.111558246143, 4556.681032327739, 4560.931586026212, 4586.72479651222, 6059.333473317932, 6075.33171351151, 6166.876662466726, 6187.0, 6216.191895053659, 5627.71861406928, 5647.209390685307, 5653.48419953121, 5715.942260786113, 5724.855028994176, 6557.93442065578, 6607.108040414579, 6629.541500869801, 6629.9005514916835, 6639.73441062357, 4875.171220892456, 4879.4876537963355, 4898.681585696954, 4898.877528061791, 4915.45929947709, 6915.827104322382, 6993.160820701498, 7007.0, 7027.683754231072, 7033.226345102034, 5008.899822003539, 5044.470330615306, 5068.391792984866, 5080.161773307437, 5103.948960510347, 5103.18349064148, 5113.488651134857, 5131.871703207375, 5135.12702840518, 5142.922856157161, 5464.863378415572, 5652.8869422611315, 5672.773089076473, 5708.657480551097, 5729.341125770507, 6211.223597050369, 6535.411812936859, 6571.605703657796, 6587.769428070091, 6611.86776264472, 1728.4814555633259, 1730.3338214787398, 1736.0364997426298, 1762.770839790819, 1765.3291749135246, 1177.9116566257565, 1196.1208511743862, 1218.3847157185564, 1239.1449899569268, 1252.5741247975732, 5553.194786755978, 5684.289463817023, 5701.8311246194435, 5789.0, 5819.563532735062, 6723.5629684071355, 6751.291114432914, 6792.898106528406, 6836.965815170893, 6844.555103918229, 5676.574256930747, 5727.914081288784, 5736.913946290809, 5758.62568933018, 5778.133280008033, 3555.982220088883, 3590.6588874056924, 3598.676119149289, 3635.8545658173907, 3638.599754027055, 6485.902711459332, 6530.249021362604, 6572.14562106923, 6614.0, 6621.304762999912, 6877.1747390313485, 7003.894941575881, 7011.824704382431, 7033.718651253893, 7071.681774320168, 6469.51478639104, 6508.251551071688, 6514.869702605826, 6523.282438931714, 6527.445167160749, 6701.832454188684, 6753.2233793112455, 6792.554680315181, 6800.285969973082, 6810.761623343159, 6625.4037136657935, 6668.699908504032, 6722.949500402424, 6734.461243100638, 6737.831554211184, 6425.293217745951, 6462.3537646235, 6477.1579694639395, 6531.9020214695865, 6571.145751052524, 4980.950190498049, 5000.799968001239, 5008.674436161784, 5032.220005899091, 5049.823256186016, 6749.797506074826, 6768.424683901923, 6816.591004539743, 6829.317068293131, 6844.828879278058, 6538.888388973791, 6542.607443553402, 6555.4427873631275, 6561.573497722633, 6563.212414510289, 6484.675766211576, 6492.935070649233, 6511.544191906521, 6576.539642224999, 6588.901166482602, 6459.386358295955, 6504.6747662615735, 6507.0, 6525.347465253627, 6591.505637077143, 6172.845678858064, 6338.968305158444, 6437.259715132633, 6596.921969579567, 6612.891940787822, 6262.34245404226, 6278.4035516625845, 6301.527385446018, 6381.202349706286, 6408.423241908251], "value": 5081.3519507522, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [908.5502676175292, 919.0258326174256, 936.8173206224789, 940.1820416237874, 947.3321308477522, 18910.716339254905, 19281.4215573533, 19762.703559446603, 19778.208871645136, 20356.69464958023, 21618.351449456542, 21883.343499694995, 21932.8903355483, 21939.890300548475, 21974.670379944313, 894.9418287811304, 923.926085913127, 924.8011677489338, 938.4979036215626, 975.4732444479972, 989.3124278626358, 992.4045572656421, 992.7518120469882, 992.8113658404916, 994.636957510507, 1037.2376303416982, 1038.0916697889347, 1041.1878734587035, 1042.7601651620093, 1049.727070961548, 1026.984595231072, 1031.7575369788094, 1035.829088200446, 1036.1555332404105, 1043.9686809395691, 760.1030783675261, 766.3486036868672, 766.5898744171841, 768.4543973778624, 769.0233403577481, 18298.359557415428, 18437.446876593724, 18456.63086738271, 18476.353327633467, 18923.43229703111, 21342.35972920815, 21466.24868129608, 21894.562108757895, 21936.670949935764, 21981.450463738376, 17081.57296067596, 17431.825681743274, 17464.0, 17473.0, 17525.0, 3674.356987527172, 3704.4258139988347, 3705.907352316187, 3706.777593344408, 3706.796126213057, 134939.0, 134996.0, 136143.31928340296, 137796.0, 138671.99999999898, 15664.216789160497, 15925.601859953595, 16051.197440127948, 16079.196040197943, 16129.274182661808, 30276.84861575678, 30702.69297307043, 30812.537811932838, 30836.84581577078, 30892.69107308943, 8794.692185773467, 8820.558972051373, 8854.911450885409, 8913.28693704502, 8965.103489650983, 3695.3348397288746, 3854.8265328060306, 3904.7657140571655, 4080.897977550585, 4234.597713217239, 5355.598330125256, 5459.0, 5464.043792336404, 5494.2857428534035, 5498.560115190775, 1013.0730381700808, 1039.0700323210683, 1056.2025670618634, 1057.2387880725864, 1058.571278632155, 662.6223052859824, 671.9227288861746, 681.3765404654785, 685.8799710050721, 690.0373978300344, 10936.726581835517, 11242.94378528102, 11376.374299413612, 11484.540618375171, 11582.536698531965, 674.780696273713, 676.5669971218424, 680.9761658342034, 699.4124935054579, 699.5872435263219, 5631.380548139701, 5664.150377443417, 5665.575081868794, 5723.141528770634, 5818.94181058184, 121630.0, 123984.0, 125141.0, 128102.0, 128601.0, 111215.44392278149, 111899.0, 112700.0, 114558.0, 117685.0, 91562.08437915535, 92632.0, 93562.53218733863, 96611.0, 96883.0, 10438.321509101885, 10648.467576620984, 10657.14742820557, 10806.891931080903, 10838.0, 43799.781001094794, 44992.0, 45073.32390014218, 45353.546464534935, 47688.0, 58758.70620646869, 59091.40908591031, 59581.10628340665, 60305.39694603173, 61920.0, 14895.702085958437, 15251.313690883715, 15413.306401211748, 15432.537023889301, 15576.065436073872, 16062.759058614363, 16248.837511624732, 16343.182840858148, 16474.505764827074, 16501.752473713143, 13519.864801351861, 13733.793993089912, 13750.862491374959, 13780.586582402544, 13801.861981380058, 14916.477923272634, 14978.325975330918, 14987.550373488813, 15203.163825989677, 15208.315625797088, 14511.782323264948, 14682.853171468149, 14781.630459238606, 14944.701105978036, 14985.0, 10134.493275336352, 10216.489175541044, 10341.689749307532, 10498.422586757802, 10646.574137034431, 11576.421178941187, 11695.298282103102, 11823.949642803549, 12155.817662735242, 12181.08641851847], "value": 8838.639774037443, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1421.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 106.0, 107.0, 106.0, 107.0, 100.0, 109.0, 107.0, 107.0, 108.0], "value": 106.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 120.0, 116.0, 119.0, 125.0, 115.0, 120.0, 117.0, 118.0, 116.0], "value": 118.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 162.0, 162.0, 160.0, 162.0, 165.0, 170.0, 167.0, 163.0], "value": 163.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 77.0, 81.0, 75.0, 73.0, 73.0, 74.0, 75.0, 75.0, 75.0], "value": 75.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 80.0, 78.0, 82.0, 78.0, 79.0, 79.0, 78.0, 79.0], "value": 79.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 114.0, 114.0, 116.0, 114.0, 111.0, 114.0, 115.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 148.0, 151.0, 150.0, 148.0, 150.0, 149.0, 148.0, 150.0, 148.0], "value": 149.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 53.0, 54.0, 53.0, 54.0, 52.0, 52.0, 53.0, 51.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 38.0, 37.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0], "value": 37.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 74.0, 78.0, 80.0, 77.0, 74.0, 80.0, 74.0, 75.0], "value": 76.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 116.0, 123.0, 116.0, 118.0, 116.0, 120.0, 122.0, 133.0, 125.0], "value": 120.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 170.0, 168.0, 168.0, 180.0, 165.0, 167.0, 171.0, 170.0, 167.0], "value": 169.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 62.0, 63.0, 64.0, 63.0, 63.0, 64.0, 61.0, 72.0, 65.0], "value": 64.3, "unit": "score"}]}]} 03:07:27 INFO - Return code: 0 03:07:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:07:27 INFO - # TBPL SUCCESS # 03:07:27 INFO - Running post-action listener: _package_coverage_data 03:07:27 INFO - Running post-action listener: _resource_record_post_action 03:07:27 INFO - [mozharness: 2017-09-08 10:07:27.472912Z] Finished run-tests step (success) 03:07:27 INFO - Running post-run listener: _resource_record_post_run 03:07:27 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 6025216; Write bytes: 417193984; Read time: 2480; Write time: 329032 03:07:27 INFO - TinderboxPrint: CPU usage
13.1% 03:07:27 INFO - TinderboxPrint: I/O read bytes / time
6,025,216 / 2,480 03:07:27 INFO - TinderboxPrint: I/O write bytes / time
417,193,984 / 329,032 03:07:27 INFO - TinderboxPrint: CPU idle
8,249.3 (86.9%) 03:07:27 INFO - TinderboxPrint: CPU user
1,192.2 (12.6%) 03:07:27 INFO - TinderboxPrint: Swap in / out
0 / 0 03:07:27 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 36950016; Read time: 0; Write time: 10832 03:07:27 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:07:27 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4280320; Write bytes: 280412160; Read time: 2424; Write time: 206892 03:07:28 INFO - Running post-run listener: _upload_blobber_files 03:07:28 INFO - Blob upload gear active. 03:07:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:07:28 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 03:07:28 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'] 03:07:28 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 03:07:28 INFO - (blobuploader) - INFO - Open directory for files ... 03:07:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:07:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:07:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:07:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:07:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:07:32 INFO - (blobuploader) - INFO - Done attempting. 03:07:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:07:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:07:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:07:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:07:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:07:32 INFO - (blobuploader) - INFO - Done attempting. 03:07:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:07:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:07:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:07:34 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:07:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:07:34 INFO - (blobuploader) - INFO - Done attempting. 03:07:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:07:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:07:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:07:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:07:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:07:35 INFO - (blobuploader) - INFO - Done attempting. 03:07:35 INFO - (blobuploader) - INFO - Iteration through files over. 03:07:35 INFO - Return code: 0 03:07:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:07:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:07:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a38c5d5f3cbf5866971c82bc625e935063da8722f4179d541112063d446dc4468302bdad554bfdfc7e3d81fe8dedecdbe21c3b6a50f06b68c203e7741fdc66ae", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4726b0af96f0c3cc51a5e453b546b1fe3f8907e3e65447c4aa27f14c4089d638e8efebd642cbbf7facf41fa8c3649d8c8c3b7be201897ada78bd5c0621bd90be", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d02f52d6ece4cddf103a367f89b641f3dcd9a86b59bfd16d01e71e445d27d9073370de44c4a39ebdc915aa75ccbd56f3d754ed8fcb72495e4cc3f6a0a1f79f02", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3021e792a139cff962fd43aee63db0dea2c88cd04028ddf1d13db6c0aebc9e271e3d167a4d400f7dd7d9aa40adbb345511f8db4b23e4a93b5e0469402ef7ec8c"} 03:07:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:07:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:07:35 INFO - Contents: 03:07:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a38c5d5f3cbf5866971c82bc625e935063da8722f4179d541112063d446dc4468302bdad554bfdfc7e3d81fe8dedecdbe21c3b6a50f06b68c203e7741fdc66ae", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4726b0af96f0c3cc51a5e453b546b1fe3f8907e3e65447c4aa27f14c4089d638e8efebd642cbbf7facf41fa8c3649d8c8c3b7be201897ada78bd5c0621bd90be", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d02f52d6ece4cddf103a367f89b641f3dcd9a86b59bfd16d01e71e445d27d9073370de44c4a39ebdc915aa75ccbd56f3d754ed8fcb72495e4cc3f6a0a1f79f02", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3021e792a139cff962fd43aee63db0dea2c88cd04028ddf1d13db6c0aebc9e271e3d167a4d400f7dd7d9aa40adbb345511f8db4b23e4a93b5e0469402ef7ec8c"} 03:07:35 INFO - Running post-run listener: copy_logs_to_upload_dir 03:07:35 INFO - Copying logs to upload dir... 03:07:35 INFO - mkdir: /builds/slave/test/build/upload/logs 03:07:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.864900 ========= master_lag: -0.26 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-08 03:07:34.909670) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-08 03:07:34.918210) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a38c5d5f3cbf5866971c82bc625e935063da8722f4179d541112063d446dc4468302bdad554bfdfc7e3d81fe8dedecdbe21c3b6a50f06b68c203e7741fdc66ae", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4726b0af96f0c3cc51a5e453b546b1fe3f8907e3e65447c4aa27f14c4089d638e8efebd642cbbf7facf41fa8c3649d8c8c3b7be201897ada78bd5c0621bd90be", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d02f52d6ece4cddf103a367f89b641f3dcd9a86b59bfd16d01e71e445d27d9073370de44c4a39ebdc915aa75ccbd56f3d754ed8fcb72495e4cc3f6a0a1f79f02", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3021e792a139cff962fd43aee63db0dea2c88cd04028ddf1d13db6c0aebc9e271e3d167a4d400f7dd7d9aa40adbb345511f8db4b23e4a93b5e0469402ef7ec8c"} build_url:https://queue.taskcluster.net/v1/task/F69fQAZfRECOnrdgihUWdw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011199 build_url: 'https://queue.taskcluster.net/v1/task/F69fQAZfRECOnrdgihUWdw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a38c5d5f3cbf5866971c82bc625e935063da8722f4179d541112063d446dc4468302bdad554bfdfc7e3d81fe8dedecdbe21c3b6a50f06b68c203e7741fdc66ae", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4726b0af96f0c3cc51a5e453b546b1fe3f8907e3e65447c4aa27f14c4089d638e8efebd642cbbf7facf41fa8c3649d8c8c3b7be201897ada78bd5c0621bd90be", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d02f52d6ece4cddf103a367f89b641f3dcd9a86b59bfd16d01e71e445d27d9073370de44c4a39ebdc915aa75ccbd56f3d754ed8fcb72495e4cc3f6a0a1f79f02", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3021e792a139cff962fd43aee63db0dea2c88cd04028ddf1d13db6c0aebc9e271e3d167a4d400f7dd7d9aa40adbb345511f8db4b23e4a93b5e0469402ef7ec8c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-08 03:07:34.953745) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-08 03:07:34.954163) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1504864013.580233-445609411 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004317 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-08 03:07:35.036968) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-08 03:07:35.037346) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-08 03:07:35.037719) ========= ========= Total master_lag: -0.08 =========